[==========] 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.628196  7314 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.7.36.190:36077
I20260812 06:18:45.629098  7314 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.629667  7314 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:45.635301  7320 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:45.635391  7314 server_base.cc:1061] running on GCE node
W20260812 06:18:45.635344  7329 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.635548  7325 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.636009  7314 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:45.636099  7314 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.636135  7314 hybrid_clock.cc:648] HybridClock initialized: now 1786515525636133 us; error 0 us; skew 500 ppm
I20260812 06:18:45.637717  7314 webserver.cc:533] Webserver started at http://127.7.36.190:41275/ using document root <none> and password file <none>
I20260812 06:18:45.638247  7314 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:45.638311  7314 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:45.638530  7314 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:45.640105  7314 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/master-0-root/instance:
uuid: "4dd0affac0b243ed85955ff9078d3a5e"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zj8q"
I20260812 06:18:45.643280  7314 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:18:45.645126  7342 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.646027  7314 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:18:45.646129  7314 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/master-0-root
uuid: "4dd0affac0b243ed85955ff9078d3a5e"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zj8q"
I20260812 06:18:45.646242  7314 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-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.667445  7314 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:45.667970  7314 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.668112  7314 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:45.675107  7314 rpc_server.cc:307] RPC server started. Bound to: 127.7.36.190:36077
I20260812 06:18:45.675108  7445 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.36.190:36077 every 8 connection(s)
I20260812 06:18:45.677125  7447 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.682078  7447 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e: Bootstrap starting.
I20260812 06:18:45.684278  7447 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:45.685091  7447 log.cc:826] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e: Log is configured to *not* fsync() on all Append() calls
I20260812 06:18:45.686609  7447 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e: No bootstrap required, opened a new log
I20260812 06:18:45.689142  7447 raft_consensus.cc:359] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4dd0affac0b243ed85955ff9078d3a5e" member_type: VOTER }
I20260812 06:18:45.689292  7447 raft_consensus.cc:385] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:45.689358  7447 raft_consensus.cc:740] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 4dd0affac0b243ed85955ff9078d3a5e, State: Initialized, Role: FOLLOWER
I20260812 06:18:45.689880  7447 consensus_queue.cc:260] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [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: "4dd0affac0b243ed85955ff9078d3a5e" member_type: VOTER }
I20260812 06:18:45.690017  7447 raft_consensus.cc:399] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:45.690080  7447 raft_consensus.cc:493] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:45.690210  7447 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:45.690891  7447 raft_consensus.cc:515] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4dd0affac0b243ed85955ff9078d3a5e" member_type: VOTER }
I20260812 06:18:45.691275  7447 leader_election.cc:304] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [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: 4dd0affac0b243ed85955ff9078d3a5e; no voters: 
I20260812 06:18:45.691543  7447 leader_election.cc:290] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:45.691641  7450 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:45.691840  7450 raft_consensus.cc:697] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 1 LEADER]: Becoming Leader. State: Replica: 4dd0affac0b243ed85955ff9078d3a5e, State: Running, Role: LEADER
I20260812 06:18:45.692248  7450 consensus_queue.cc:237] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [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: "4dd0affac0b243ed85955ff9078d3a5e" member_type: VOTER }
I20260812 06:18:45.692382  7447 sys_catalog.cc:565] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:45.693922  7452 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "4dd0affac0b243ed85955ff9078d3a5e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4dd0affac0b243ed85955ff9078d3a5e" member_type: VOTER } }
I20260812 06:18:45.693907  7457 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4dd0affac0b243ed85955ff9078d3a5e. Latest consensus state: current_term: 1 leader_uuid: "4dd0affac0b243ed85955ff9078d3a5e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "4dd0affac0b243ed85955ff9078d3a5e" member_type: VOTER } }
I20260812 06:18:45.694036  7457 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:45.694036  7452 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:45.694411  7472 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:45.694530  7314 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:18:45.696471  7472 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:45.700819  7472 catalog_manager.cc:1383] Generated new cluster ID: bc19756e1246496d93d611d7a25a7119
I20260812 06:18:45.700870  7472 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:45.708549  7472 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:45.709240  7472 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:45.714185  7472 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e: Generated new TSK 0
I20260812 06:18:45.714668  7472 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:45.726920  7314 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:45.729275  7482 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.729328  7481 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:45.729373  7314 server_base.cc:1061] running on GCE node
W20260812 06:18:45.729282  7485 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.729692  7314 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:45.729737  7314 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.729756  7314 hybrid_clock.cc:648] HybridClock initialized: now 1786515525729756 us; error 0 us; skew 500 ppm
I20260812 06:18:45.730625  7314 webserver.cc:533] Webserver started at http://127.7.36.129:36401/ using document root <none> and password file <none>
I20260812 06:18:45.730795  7314 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:45.730844  7314 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:45.730917  7314 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:45.731256  7314 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/instance:
uuid: "fb7b522219234cdfb34e41fc7152d756"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zj8q"
I20260812 06:18:45.732713  7314 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:18:45.733634  7491 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.733865  7314 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:18:45.733934  7314 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root
uuid: "fb7b522219234cdfb34e41fc7152d756"
format_stamp: "Formatted at 2026-08-12 06:18:45 on dist-test-slave-zj8q"
I20260812 06:18:45.734002  7314 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-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:45.749250  7314 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:45.749617  7314 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:45.750058  7314 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:45.750927  7314 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:45.750982  7314 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:45.751026  7314 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:45.751056  7314 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:45.757166  7314 rpc_server.cc:307] RPC server started. Bound to: 127.7.36.129:45861
I20260812 06:18:45.757221  7609 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.36.129:45861 every 8 connection(s)
I20260812 06:18:45.769026  7610 heartbeater.cc:344] Connected to a master server at 127.7.36.190:36077
I20260812 06:18:45.769260  7610 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:45.769697  7610 heartbeater.cc:507] Master 127.7.36.190:36077 requested a full tablet report, sending...
I20260812 06:18:45.771076  7377 ts_manager.cc:194] Registered new tserver with Master: fb7b522219234cdfb34e41fc7152d756 (127.7.36.129:45861)
I20260812 06:18:45.771142  7314 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.013387642s
I20260812 06:18:45.772547  7377 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:38120
I20260812 06:18:45.779824  7377 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:38136:
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:45.793056  7539 tablet_service.cc:1511] Processing CreateTablet for tablet c99b5fc50997423da9ef5003d8fb59d3 (DEFAULT_TABLE table=heavy-update-compaction-test [id=40f1f8c1c8024ef4a27df7c889c84766]), partition=
I20260812 06:18:45.793558  7539 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet c99b5fc50997423da9ef5003d8fb59d3. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:45.796873  7634 tablet_bootstrap.cc:492] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Bootstrap starting.
I20260812 06:18:45.798763  7634 tablet_bootstrap.cc:654] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:45.800599  7634 tablet_bootstrap.cc:492] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: No bootstrap required, opened a new log
I20260812 06:18:45.800711  7634 ts_tablet_manager.cc:1403] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Time spent bootstrapping tablet: real 0.004s	user 0.000s	sys 0.003s
I20260812 06:18:45.801641  7634 raft_consensus.cc:359] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fb7b522219234cdfb34e41fc7152d756" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 45861 } }
I20260812 06:18:45.801743  7634 raft_consensus.cc:385] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:45.801765  7634 raft_consensus.cc:740] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fb7b522219234cdfb34e41fc7152d756, State: Initialized, Role: FOLLOWER
I20260812 06:18:45.801879  7634 consensus_queue.cc:260] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [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: "fb7b522219234cdfb34e41fc7152d756" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 45861 } }
I20260812 06:18:45.801947  7634 raft_consensus.cc:399] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:45.801983  7634 raft_consensus.cc:493] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:45.802031  7634 raft_consensus.cc:3060] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:45.803248  7634 raft_consensus.cc:515] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fb7b522219234cdfb34e41fc7152d756" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 45861 } }
I20260812 06:18:45.803366  7634 leader_election.cc:304] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [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: fb7b522219234cdfb34e41fc7152d756; no voters: 
I20260812 06:18:45.803539  7634 leader_election.cc:290] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:45.803725  7638 raft_consensus.cc:2804] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:45.803864  7634 ts_tablet_manager.cc:1434] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.004s
I20260812 06:18:45.803992  7638 raft_consensus.cc:697] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 1 LEADER]: Becoming Leader. State: Replica: fb7b522219234cdfb34e41fc7152d756, State: Running, Role: LEADER
I20260812 06:18:45.804131  7638 consensus_queue.cc:237] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [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: "fb7b522219234cdfb34e41fc7152d756" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 45861 } }
I20260812 06:18:45.804245  7610 heartbeater.cc:499] Master 127.7.36.190:36077 was elected leader, sending a full tablet report...
I20260812 06:18:45.806748  7377 catalog_manager.cc:5719] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 reported cstate change: term changed from 0 to 1, leader changed from <none> to fb7b522219234cdfb34e41fc7152d756 (127.7.36.129). New cstate: current_term: 1 leader_uuid: "fb7b522219234cdfb34e41fc7152d756" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fb7b522219234cdfb34e41fc7152d756" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 45861 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:45.953236  7314 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.140s	user 0.027s	sys 0.030s
I20260812 06:18:46.008211  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=6.156503
I20260812 06:18:46.125991  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.117s	user 0.093s	sys 0.020s Metrics: {"bytes_written":8533272,"cfile_init":1,"compiler_manager_pool.queue_time_us":207,"delete_count":0,"dirs.queue_time_us":38,"dirs.run_cpu_time_us":178,"dirs.run_wall_time_us":785,"drs_written":1,"lbm_read_time_us":71,"lbm_reads_lt_1ms":4,"lbm_write_time_us":22596,"lbm_writes_lt_1ms":365,"peak_mem_usage":0,"reinsert_count":0,"rows_written":101,"spinlock_wait_cycles":300800,"thread_start_us":111,"threads_started":1,"update_count":1040}
I20260812 06:18:46.127463  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling LogGCOp(c99b5fc50997423da9ef5003d8fb59d3): free 11976772 bytes of WAL
I20260812 06:18:46.127858  7501 log_reader.cc:385] T c99b5fc50997423da9ef5003d8fb59d3: removed 1 log segments from log reader
I20260812 06:18:46.128013  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000001 (ops 1-6)
I20260812 06:18:46.131421  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: LogGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.004s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:18:46.131829  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3): 4103815 bytes on disk
I20260812 06:18:46.132280  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":45,"lbm_reads_lt_1ms":4}
I20260812 06:18:46.132629  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:46.154400  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.022s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4184711,"delete_count":0,"lbm_write_time_us":3805,"lbm_writes_lt_1ms":105,"reinsert_count":0,"update_count":510}
I20260812 06:18:46.154858  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:46.167313  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.012s	user 0.012s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4648,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:46.167738  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:46.309098  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.141s	user 0.106s	sys 0.028s Metrics: {"cfile_cache_miss":433,"cfile_cache_miss_bytes":20549493,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":745,"lbm_read_time_us":9310,"lbm_reads_lt_1ms":469,"lbm_write_time_us":22170,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":11392,"thread_start_us":253,"threads_started":5,"update_count":2000}
I20260812 06:18:46.309610  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=10.126437
I20260812 06:18:46.340224  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.030s	user 0.015s	sys 0.012s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":12845,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:46.340627  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:46.436447  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.096s	user 0.080s	sys 0.016s Metrics: {"cfile_cache_miss":331,"cfile_cache_miss_bytes":16446852,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":589,"lbm_read_time_us":5582,"lbm_reads_lt_1ms":363,"lbm_write_time_us":19634,"lbm_writes_lt_1ms":343,"mutex_wait_us":67,"peak_mem_usage":38262756,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":1500}
I20260812 06:18:46.437004  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=10.126437
I20260812 06:18:46.476277  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.039s	user 0.019s	sys 0.009s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":12750,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:46.476791  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:46.491186  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.014s	user 0.003s	sys 0.010s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5244,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:46.491711  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:46.617511  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.126s	user 0.091s	sys 0.033s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20549380,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":752,"lbm_read_time_us":7600,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24982,"lbm_writes_lt_1ms":443,"mutex_wait_us":420,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":2000}
I20260812 06:18:46.618044  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=10.126437
I20260812 06:18:46.662858  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.045s	user 0.025s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18790,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:18:46.663276  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:46.673038  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3654,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:46.673418  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:46.793349  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.120s	user 0.099s	sys 0.020s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20549381,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1372,"lbm_read_time_us":7911,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28175,"lbm_writes_lt_1ms":443,"mutex_wait_us":533,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":2000}
I20260812 06:18:46.794013  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=10.126437
I20260812 06:18:46.838348  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.044s	user 0.024s	sys 0.020s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":20725,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:46.838843  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:46.856467  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.017s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7097,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:46.856922  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:46.984802  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.128s	user 0.105s	sys 0.016s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20549381,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":154,"lbm_read_time_us":9178,"lbm_reads_lt_1ms":468,"lbm_write_time_us":26064,"lbm_writes_lt_1ms":443,"mutex_wait_us":2,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1920,"update_count":2000}
I20260812 06:18:46.985275  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=11.118625
I20260812 06:18:47.031291  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.046s	user 0.022s	sys 0.023s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":18468,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:47.031970  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:47.048046  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.016s	user 0.014s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6618,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:47.048523  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:47.193572  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.145s	user 0.098s	sys 0.044s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20549375,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":211,"lbm_read_time_us":9008,"lbm_reads_lt_1ms":468,"lbm_write_time_us":28458,"lbm_writes_lt_1ms":443,"mutex_wait_us":27,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":6912,"update_count":2000}
I20260812 06:18:47.194089  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=11.118625
I20260812 06:18:47.233281  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.039s	user 0.011s	sys 0.025s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":17692,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:47.233717  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:47.255815  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.022s	user 0.010s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4692,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:47.256294  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:47.270725  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.014s	user 0.007s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5509,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.271214  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:47.303763  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.032s	user 0.022s	sys 0.004s Metrics: {"bytes_written":1152509,"cfile_init":1,"dirs.queue_time_us":58,"dirs.run_cpu_time_us":192,"dirs.run_wall_time_us":1464,"drs_written":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1535,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:18:47.304515  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling LogGCOp(c99b5fc50997423da9ef5003d8fb59d3): free 108082406 bytes of WAL
I20260812 06:18:47.304733  7501 log_reader.cc:385] T c99b5fc50997423da9ef5003d8fb59d3: removed 11 log segments from log reader
I20260812 06:18:47.304782  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000002 (ops 7-11)
I20260812 06:18:47.304808  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000003 (ops 12-16)
I20260812 06:18:47.304834  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000004 (ops 17-20)
I20260812 06:18:47.304867  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000005 (ops 21-25)
I20260812 06:18:47.304893  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000006 (ops 26-30)
I20260812 06:18:47.304924  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000007 (ops 31-34)
I20260812 06:18:47.304944  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000008 (ops 35-39)
I20260812 06:18:47.304975  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000009 (ops 40-44)
I20260812 06:18:47.305006  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000010 (ops 45-49)
I20260812 06:18:47.305037  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000011 (ops 50-54)
I20260812 06:18:47.305069  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000012 (ops 55-58)
I20260812 06:18:47.324266  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: LogGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.020s	user 0.000s	sys 0.018s Metrics: {}
I20260812 06:18:47.324651  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:47.348325  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.024s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5724,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.348748  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:47.359555  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4123,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.359987  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3): 447 bytes on disk
I20260812 06:18:47.360409  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":50,"lbm_reads_lt_1ms":4}
I20260812 06:18:47.360993  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:47.573913  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.213s	user 0.156s	sys 0.055s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":32856968,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":601,"lbm_read_time_us":15386,"lbm_reads_lt_1ms":775,"lbm_write_time_us":41662,"lbm_writes_lt_1ms":743,"mutex_wait_us":266,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":896,"thread_start_us":65,"threads_started":1,"update_count":3500}
I20260812 06:18:47.574572  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=16.079562
I20260812 06:18:47.626914  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.052s	user 0.025s	sys 0.021s Metrics: {"bytes_written":18091890,"delete_count":0,"lbm_write_time_us":20907,"lbm_writes_lt_1ms":444,"reinsert_count":0,"update_count":2205}
I20260812 06:18:47.627472  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.196750
I20260812 06:18:47.637773  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.010s	user 0.006s	sys 0.001s Metrics: {"bytes_written":2830884,"delete_count":0,"lbm_write_time_us":2746,"lbm_writes_lt_1ms":72,"reinsert_count":0,"update_count":345}
I20260812 06:18:47.638226  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:47.646831  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.008s	user 0.002s	sys 0.006s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3183,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:47.647199  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:47.827072  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.180s	user 0.128s	sys 0.051s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28754284,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":161,"lbm_read_time_us":12837,"lbm_reads_lt_1ms":673,"lbm_write_time_us":32385,"lbm_writes_lt_1ms":643,"mutex_wait_us":42,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":13056,"update_count":3000}
I20260812 06:18:47.827576  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:47.887998  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.060s	user 0.039s	sys 0.014s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":25963,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:47.888525  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:47.907833  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.019s	user 0.005s	sys 0.012s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":8954,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:47.908306  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:48.086303  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.178s	user 0.101s	sys 0.076s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24651794,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":748,"lbm_read_time_us":10432,"lbm_reads_lt_1ms":564,"lbm_write_time_us":41175,"lbm_writes_lt_1ms":543,"mutex_wait_us":59,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":4992,"update_count":2500}
I20260812 06:18:48.086845  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:48.142949  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.056s	user 0.027s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":27330,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:48.143537  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:48.158401  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.015s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6170,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:48.158842  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:48.313454  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.154s	user 0.102s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24651793,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":296,"lbm_read_time_us":10380,"lbm_reads_lt_1ms":568,"lbm_write_time_us":28677,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:48.314073  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:48.371093  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.057s	user 0.028s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":20407,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:48.371722  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:48.383927  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.012s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4688,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:48.384419  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:48.550845  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.166s	user 0.110s	sys 0.056s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24651793,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":210,"lbm_read_time_us":11289,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27679,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":384,"update_count":2500}
I20260812 06:18:48.551357  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:48.600276  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.049s	user 0.031s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23466,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:48.600759  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:48.619029  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.018s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4375,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:48.619489  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:48.652113  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.032s	user 0.022s	sys 0.009s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":63,"dirs.run_cpu_time_us":187,"dirs.run_wall_time_us":1294,"drs_written":1,"lbm_read_time_us":38,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1342,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:18:48.652947  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling LogGCOp(c99b5fc50997423da9ef5003d8fb59d3): free 121006439 bytes of WAL
I20260812 06:18:48.653208  7501 log_reader.cc:385] T c99b5fc50997423da9ef5003d8fb59d3: removed 12 log segments from log reader
I20260812 06:18:48.653262  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000013 (ops 59-63)
I20260812 06:18:48.653303  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000014 (ops 64-68)
I20260812 06:18:48.653337  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000015 (ops 69-73)
I20260812 06:18:48.653363  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000016 (ops 74-78)
I20260812 06:18:48.653394  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000017 (ops 79-83)
I20260812 06:18:48.653426  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000018 (ops 84-88)
I20260812 06:18:48.653458  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000019 (ops 89-92)
I20260812 06:18:48.653488  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000020 (ops 93-97)
I20260812 06:18:48.653519  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000021 (ops 98-102)
I20260812 06:18:48.653549  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000022 (ops 103-107)
I20260812 06:18:48.653573  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000023 (ops 108-112)
I20260812 06:18:48.653596  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000024 (ops 113-117)
I20260812 06:18:48.677287  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: LogGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.024s	user 0.000s	sys 0.021s Metrics: {}
I20260812 06:18:48.677793  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3): 460 bytes on disk
I20260812 06:18:48.679062  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4}
I20260812 06:18:48.679670  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=4.173312
I20260812 06:18:48.692420  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.013s	user 0.008s	sys 0.003s Metrics: {"bytes_written":5620561,"delete_count":0,"lbm_write_time_us":5025,"lbm_writes_lt_1ms":140,"reinsert_count":0,"update_count":685}
I20260812 06:18:48.692826  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.196750
I20260812 06:18:48.700469  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.007s	user 0.005s	sys 0.000s Metrics: {"bytes_written":2584729,"delete_count":0,"lbm_write_time_us":2304,"lbm_writes_lt_1ms":66,"reinsert_count":0,"update_count":315}
I20260812 06:18:48.700980  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:48.924397  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.223s	user 0.133s	sys 0.083s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32856821,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":165,"lbm_read_time_us":15168,"lbm_reads_lt_1ms":774,"lbm_write_time_us":35109,"lbm_writes_lt_1ms":743,"mutex_wait_us":30,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":12544,"thread_start_us":73,"threads_started":1,"update_count":3500}
I20260812 06:18:48.925280  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=18.063937
I20260812 06:18:48.984894  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.059s	user 0.044s	sys 0.012s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":26957,"lbm_writes_lt_1ms":503,"reinsert_count":0,"spinlock_wait_cycles":3968,"update_count":2500}
I20260812 06:18:48.985404  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:49.146256  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.161s	user 0.105s	sys 0.048s Metrics: {"cfile_cache_miss":531,"cfile_cache_miss_bytes":24651676,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":394,"lbm_read_time_us":11725,"lbm_reads_lt_1ms":563,"lbm_write_time_us":25569,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":23168,"update_count":2500}
I20260812 06:18:49.146721  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=15.087375
I20260812 06:18:49.201510  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.055s	user 0.024s	sys 0.027s Metrics: {"bytes_written":16820143,"delete_count":0,"lbm_write_time_us":20833,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:18:49.202138  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=3.181125
I20260812 06:18:49.216130  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.014s	user 0.011s	sys 0.001s Metrics: {"bytes_written":5005192,"delete_count":0,"lbm_write_time_us":5344,"lbm_writes_lt_1ms":125,"reinsert_count":0,"update_count":610}
I20260812 06:18:49.216553  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.196750
I20260812 06:18:49.227101  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.010s	user 0.003s	sys 0.006s Metrics: {"bytes_written":2789855,"delete_count":0,"lbm_write_time_us":3769,"lbm_writes_lt_1ms":71,"reinsert_count":0,"update_count":340}
I20260812 06:18:49.227547  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:49.409582  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.182s	user 0.132s	sys 0.050s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28754295,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":455,"lbm_read_time_us":13506,"lbm_reads_lt_1ms":673,"lbm_write_time_us":31591,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":3000}
I20260812 06:18:49.410180  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:49.448549  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.038s	user 0.034s	sys 0.002s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":17042,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:49.449093  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:49.461599  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.012s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4112,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.462103  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:49.626590  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.164s	user 0.101s	sys 0.063s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24651793,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":665,"lbm_read_time_us":11269,"lbm_reads_lt_1ms":564,"lbm_write_time_us":28253,"lbm_writes_lt_1ms":543,"mutex_wait_us":294,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2500}
I20260812 06:18:49.627104  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:49.672902  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.046s	user 0.025s	sys 0.017s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":20614,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:49.673439  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:49.688791  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.015s	user 0.006s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5990,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.689323  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:49.845034  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.156s	user 0.102s	sys 0.051s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24651793,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":204,"lbm_read_time_us":11423,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26992,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6528,"update_count":2500}
I20260812 06:18:49.845610  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:49.897891  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.052s	user 0.029s	sys 0.018s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":20983,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:18:49.898488  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:49.908339  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3723,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:49.908839  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:50.086283  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.177s	user 0.107s	sys 0.062s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24651792,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":637,"lbm_read_time_us":12492,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29228,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:50.086839  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=14.095187
I20260812 06:18:50.139614  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.053s	user 0.029s	sys 0.021s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":27162,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":400,"reinsert_count":0,"update_count":2000}
I20260812 06:18:50.140174  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=2.188937
I20260812 06:18:50.157054  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.017s	user 0.001s	sys 0.013s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4345,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:50.157541  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:50.190008  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushMRSOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.032s	user 0.027s	sys 0.004s Metrics: {"bytes_written":1316416,"cfile_init":1,"dirs.queue_time_us":63,"dirs.run_cpu_time_us":212,"dirs.run_wall_time_us":1299,"drs_written":1,"lbm_read_time_us":37,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1493,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:18:50.190949  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling LogGCOp(c99b5fc50997423da9ef5003d8fb59d3): free 133024559 bytes of WAL
I20260812 06:18:50.191206  7501 log_reader.cc:385] T c99b5fc50997423da9ef5003d8fb59d3: removed 13 log segments from log reader
I20260812 06:18:50.191263  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000025 (ops 118-122)
I20260812 06:18:50.191311  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000026 (ops 123-127)
I20260812 06:18:50.191347  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000027 (ops 128-132)
I20260812 06:18:50.191380  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000028 (ops 133-137)
I20260812 06:18:50.191412  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000029 (ops 138-142)
I20260812 06:18:50.191445  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000030 (ops 143-146)
I20260812 06:18:50.191478  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000031 (ops 147-151)
I20260812 06:18:50.191512  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000032 (ops 152-156)
I20260812 06:18:50.191545  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000033 (ops 157-161)
I20260812 06:18:50.191576  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000034 (ops 162-166)
I20260812 06:18:50.191609  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000035 (ops 167-171)
I20260812 06:18:50.191641  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000036 (ops 172-176)
I20260812 06:18:50.191674  7501 log.cc:1079] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/c99b5fc50997423da9ef5003d8fb59d3/wal-000000037 (ops 177-181)
I20260812 06:18:50.222998  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: LogGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.032s	user 0.000s	sys 0.030s Metrics: {}
I20260812 06:18:50.223753  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=6.157687
I20260812 06:18:50.242256  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.018s	user 0.012s	sys 0.006s Metrics: {"bytes_written":7466644,"delete_count":0,"lbm_write_time_us":7386,"lbm_writes_lt_1ms":185,"reinsert_count":0,"update_count":910}
I20260812 06:18:50.242899  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3): 492 bytes on disk
I20260812 06:18:50.243534  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: UndoDeltaBlockGCOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":83,"lbm_reads_lt_1ms":4}
I20260812 06:18:50.244313  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:50.454396  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.210s	user 0.147s	sys 0.059s Metrics: {"cfile_cache_miss":715,"cfile_cache_miss_bytes":32118307,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":3119,"dirs.run_cpu_time_us":443,"dirs.run_wall_time_us":4440,"lbm_read_time_us":13562,"lbm_reads_lt_1ms":751,"lbm_write_time_us":38365,"lbm_writes_lt_1ms":725,"mutex_wait_us":2013,"peak_mem_usage":85149646,"reinsert_count":0,"spinlock_wait_cycles":1280,"thread_start_us":76,"threads_started":1,"update_count":3410}
I20260812 06:18:50.455747  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=16.079562
I20260812 06:18:50.514020  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.058s	user 0.023s	sys 0.020s Metrics: {"bytes_written":17558584,"delete_count":0,"lbm_write_time_us":19607,"lbm_writes_lt_1ms":431,"reinsert_count":0,"update_count":2140}
I20260812 06:18:50.514555  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=6.157687
I20260812 06:18:50.533072  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: FlushDeltaMemStoresOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.018s	user 0.012s	sys 0.004s Metrics: {"bytes_written":7794837,"delete_count":0,"lbm_write_time_us":7558,"lbm_writes_lt_1ms":193,"reinsert_count":0,"update_count":950}
I20260812 06:18:50.533587  7611 maintenance_manager.cc:419] P fb7b522219234cdfb34e41fc7152d756: Scheduling MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3): perf score=1.000000
I20260812 06:18:50.595198  7314 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.642s	user 1.689s	sys 0.138s
I20260812 06:18:50.667909  7314 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.072s	user 0.003s	sys 0.000s
I20260812 06:18:50.668603  7314 tablet_server.cc:179] TabletServer@127.7.36.129:0 shutting down...
I20260812 06:18:50.702732  7501 maintenance_manager.cc:643] P fb7b522219234cdfb34e41fc7152d756: MajorDeltaCompactionOp(c99b5fc50997423da9ef5003d8fb59d3) complete. Timing: real 0.169s	user 0.122s	sys 0.047s Metrics: {"cfile_cache_miss":650,"cfile_cache_miss_bytes":29492648,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":701,"lbm_read_time_us":11507,"lbm_reads_lt_1ms":686,"lbm_write_time_us":27362,"lbm_writes_lt_1ms":661,"mutex_wait_us":80,"peak_mem_usage":77321486,"reinsert_count":0,"spinlock_wait_cycles":9600,"update_count":3090}
I20260812 06:18:50.703321  7314 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:50.703734  7314 tablet_replica.cc:333] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756: stopping tablet replica
I20260812 06:18:50.703951  7314 raft_consensus.cc:2243] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:50.704164  7314 raft_consensus.cc:2272] T c99b5fc50997423da9ef5003d8fb59d3 P fb7b522219234cdfb34e41fc7152d756 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:50.720341  7314 tablet_server.cc:196] TabletServer@127.7.36.129:0 shutdown complete.
I20260812 06:18:50.756894  7314 master.cc:562] Master@127.7.36.190:36077 shutting down...
I20260812 06:18:50.759850  7314 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:50.760026  7314 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:50.760097  7314 tablet_replica.cc:333] T 00000000000000000000000000000000 P 4dd0affac0b243ed85955ff9078d3a5e: stopping tablet replica
I20260812 06:18:50.772112  7314 master.cc:584] Master@127.7.36.190:36077 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5221 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:18:50.849377  7314 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.7.36.190:35469
I20260812 06:18:50.849761  7314 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:50.851665  7669 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:50.851790  7674 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:50.851680  7668 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:50.851797  7314 server_base.cc:1061] running on GCE node
I20260812 06:18:50.852094  7314 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:50.852134  7314 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:50.852154  7314 hybrid_clock.cc:648] HybridClock initialized: now 1786515530852154 us; error 0 us; skew 500 ppm
I20260812 06:18:50.852926  7314 webserver.cc:533] Webserver started at http://127.7.36.190:41863/ using document root <none> and password file <none>
I20260812 06:18:50.853087  7314 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:50.853132  7314 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:50.853209  7314 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:50.853564  7314 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/master-0-root/instance:
uuid: "08606b868f914f75938e1512a4bae16e"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-zj8q"
I20260812 06:18:50.855084  7314 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:18:50.855937  7685 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:50.856144  7314 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:18:50.856216  7314 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/master-0-root
uuid: "08606b868f914f75938e1512a4bae16e"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-zj8q"
I20260812 06:18:50.856282  7314 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-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:50.865957  7314 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:50.866866  7314 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:50.870594  7314 rpc_server.cc:307] RPC server started. Bound to: 127.7.36.190:35469
I20260812 06:18:50.880299  7769 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.36.190:35469 every 8 connection(s)
I20260812 06:18:50.880709  7770 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:50.882427  7770 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e: Bootstrap starting.
I20260812 06:18:50.883162  7770 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:50.884049  7770 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e: No bootstrap required, opened a new log
I20260812 06:18:50.884425  7770 raft_consensus.cc:359] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "08606b868f914f75938e1512a4bae16e" member_type: VOTER }
I20260812 06:18:50.884510  7770 raft_consensus.cc:385] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:50.884541  7770 raft_consensus.cc:740] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 08606b868f914f75938e1512a4bae16e, State: Initialized, Role: FOLLOWER
I20260812 06:18:50.884685  7770 consensus_queue.cc:260] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [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: "08606b868f914f75938e1512a4bae16e" member_type: VOTER }
I20260812 06:18:50.884760  7770 raft_consensus.cc:399] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:50.884797  7770 raft_consensus.cc:493] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:50.884846  7770 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:50.885489  7770 raft_consensus.cc:515] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "08606b868f914f75938e1512a4bae16e" member_type: VOTER }
I20260812 06:18:50.885629  7770 leader_election.cc:304] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [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: 08606b868f914f75938e1512a4bae16e; no voters: 
I20260812 06:18:50.885810  7770 leader_election.cc:290] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:50.885905  7775 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:50.886086  7775 raft_consensus.cc:697] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 1 LEADER]: Becoming Leader. State: Replica: 08606b868f914f75938e1512a4bae16e, State: Running, Role: LEADER
I20260812 06:18:50.886227  7775 consensus_queue.cc:237] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [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: "08606b868f914f75938e1512a4bae16e" member_type: VOTER }
I20260812 06:18:50.886276  7770 sys_catalog.cc:565] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:50.886633  7776 sys_catalog.cc:455] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "08606b868f914f75938e1512a4bae16e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "08606b868f914f75938e1512a4bae16e" member_type: VOTER } }
I20260812 06:18:50.886660  7779 sys_catalog.cc:455] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 08606b868f914f75938e1512a4bae16e. Latest consensus state: current_term: 1 leader_uuid: "08606b868f914f75938e1512a4bae16e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "08606b868f914f75938e1512a4bae16e" member_type: VOTER } }
I20260812 06:18:50.886725  7776 sys_catalog.cc:458] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:50.886744  7779 sys_catalog.cc:458] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:50.887238  7781 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:50.887959  7781 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:50.888126  7314 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:18:50.889591  7781 catalog_manager.cc:1383] Generated new cluster ID: 58303674be224354b2001aaff840dc32
I20260812 06:18:50.889643  7781 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:50.893579  7781 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:50.894053  7781 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:50.901790  7781 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e: Generated new TSK 0
I20260812 06:18:50.901926  7781 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:50.904057  7314 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:50.905591  7810 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:50.905675  7314 server_base.cc:1061] running on GCE node
W20260812 06:18:50.905719  7815 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:50.905757  7809 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:50.905920  7314 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:50.905962  7314 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:50.905975  7314 hybrid_clock.cc:648] HybridClock initialized: now 1786515530905976 us; error 0 us; skew 500 ppm
I20260812 06:18:50.906700  7314 webserver.cc:533] Webserver started at http://127.7.36.129:46665/ using document root <none> and password file <none>
I20260812 06:18:50.906824  7314 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:50.906863  7314 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:50.906912  7314 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:50.907198  7314 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/instance:
uuid: "1c25c8517efd42158059821716069aa9"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-zj8q"
I20260812 06:18:50.908473  7314 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:18:50.909246  7823 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:50.909438  7314 fs_manager.cc:730] Time spent opening block manager: real 0.000s	user 0.000s	sys 0.001s
I20260812 06:18:50.909505  7314 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root
uuid: "1c25c8517efd42158059821716069aa9"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-zj8q"
I20260812 06:18:50.909569  7314 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-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:50.917343  7314 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:50.917608  7314 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:50.917850  7314 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:50.918294  7314 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:50.918334  7314 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:50.918375  7314 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:50.918402  7314 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:50.922339  7314 rpc_server.cc:307] RPC server started. Bound to: 127.7.36.129:46239
I20260812 06:18:50.922920  7937 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.7.36.129:46239 every 8 connection(s)
I20260812 06:18:50.930787  7938 heartbeater.cc:344] Connected to a master server at 127.7.36.190:35469
I20260812 06:18:50.930871  7938 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:50.931051  7938 heartbeater.cc:507] Master 127.7.36.190:35469 requested a full tablet report, sending...
I20260812 06:18:50.931612  7712 ts_manager.cc:194] Registered new tserver with Master: 1c25c8517efd42158059821716069aa9 (127.7.36.129:46239)
I20260812 06:18:50.931672  7314 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.008751288s
I20260812 06:18:50.932314  7712 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:48132
I20260812 06:18:50.937767  7712 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:48134:
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:50.945590  7883 tablet_service.cc:1511] Processing CreateTablet for tablet 82d94480b76445009f53b295c4148ad5 (DEFAULT_TABLE table=heavy-update-compaction-test [id=f857a9d7e4994a20b3fc37dee092be41]), partition=
I20260812 06:18:50.945814  7883 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 82d94480b76445009f53b295c4148ad5. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:50.947633  7959 tablet_bootstrap.cc:492] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Bootstrap starting.
I20260812 06:18:50.948331  7959 tablet_bootstrap.cc:654] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:50.949208  7959 tablet_bootstrap.cc:492] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: No bootstrap required, opened a new log
I20260812 06:18:50.949281  7959 ts_tablet_manager.cc:1403] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Time spent bootstrapping tablet: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:18:50.949612  7959 raft_consensus.cc:359] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1c25c8517efd42158059821716069aa9" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 46239 } }
I20260812 06:18:50.949708  7959 raft_consensus.cc:385] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:50.949743  7959 raft_consensus.cc:740] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1c25c8517efd42158059821716069aa9, State: Initialized, Role: FOLLOWER
I20260812 06:18:50.949865  7959 consensus_queue.cc:260] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [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: "1c25c8517efd42158059821716069aa9" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 46239 } }
I20260812 06:18:50.949935  7959 raft_consensus.cc:399] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:50.949972  7959 raft_consensus.cc:493] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:50.950021  7959 raft_consensus.cc:3060] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:50.950714  7959 raft_consensus.cc:515] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1c25c8517efd42158059821716069aa9" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 46239 } }
I20260812 06:18:50.950847  7959 leader_election.cc:304] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [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: 1c25c8517efd42158059821716069aa9; no voters: 
I20260812 06:18:50.951025  7959 leader_election.cc:290] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:50.951125  7962 raft_consensus.cc:2804] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:50.951298  7962 raft_consensus.cc:697] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 1 LEADER]: Becoming Leader. State: Replica: 1c25c8517efd42158059821716069aa9, State: Running, Role: LEADER
I20260812 06:18:50.951421  7938 heartbeater.cc:499] Master 127.7.36.190:35469 was elected leader, sending a full tablet report...
I20260812 06:18:50.951481  7962 consensus_queue.cc:237] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [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: "1c25c8517efd42158059821716069aa9" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 46239 } }
I20260812 06:18:50.951673  7959 ts_tablet_manager.cc:1434] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Time spent starting tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:18:50.952692  7712 catalog_manager.cc:5719] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 reported cstate change: term changed from 0 to 1, leader changed from <none> to 1c25c8517efd42158059821716069aa9 (127.7.36.129). New cstate: current_term: 1 leader_uuid: "1c25c8517efd42158059821716069aa9" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "1c25c8517efd42158059821716069aa9" member_type: VOTER last_known_addr { host: "127.7.36.129" port: 46239 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:51.003774  7314 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.048s	user 0.009s	sys 0.012s
I20260812 06:18:51.173391  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushMRSOp(82d94480b76445009f53b295c4148ad5): perf score=23.023690
I20260812 06:18:51.327632  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushMRSOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.154s	user 0.101s	sys 0.051s Metrics: {"bytes_written":13210027,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":46,"dirs.run_cpu_time_us":194,"dirs.run_wall_time_us":878,"drs_written":1,"lbm_read_time_us":37,"lbm_reads_lt_1ms":4,"lbm_write_time_us":40874,"lbm_writes_lt_1ms":879,"peak_mem_usage":0,"reinsert_count":0,"rows_written":105,"spinlock_wait_cycles":2304,"update_count":1610}
I20260812 06:18:51.328394  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling LogGCOp(82d94480b76445009f53b295c4148ad5): free 20743880 bytes of WAL
I20260812 06:18:51.328625  7838 log_reader.cc:385] T 82d94480b76445009f53b295c4148ad5: removed 2 log segments from log reader
I20260812 06:18:51.328685  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000001 (ops 1-6)
I20260812 06:18:51.328779  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000002 (ops 7-11)
I20260812 06:18:51.332816  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: LogGCOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.004s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:18:51.333130  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5): 20513815 bytes on disk
I20260812 06:18:51.333549  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5) 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:51.334012  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:51.343343  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4020613,"delete_count":0,"lbm_write_time_us":3427,"lbm_writes_lt_1ms":101,"reinsert_count":0,"update_count":490}
I20260812 06:18:51.343715  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:51.351505  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.008s	user 0.006s	sys 0.000s Metrics: {"bytes_written":3282155,"delete_count":0,"lbm_write_time_us":2802,"lbm_writes_lt_1ms":83,"reinsert_count":0,"update_count":400}
I20260812 06:18:51.351907  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:51.514328  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.162s	user 0.124s	sys 0.032s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24815790,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":500,"lbm_read_time_us":11234,"lbm_reads_lt_1ms":569,"lbm_write_time_us":30124,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2048,"thread_start_us":341,"threads_started":5,"update_count":2500}
I20260812 06:18:51.514835  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:51.560659  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.046s	user 0.027s	sys 0.012s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":18151,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:51.561127  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:51.571213  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.010s	user 0.000s	sys 0.009s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3764,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:51.571841  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:51.734856  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.163s	user 0.122s	sys 0.029s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":146,"lbm_read_time_us":11527,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27029,"lbm_writes_lt_1ms":543,"mutex_wait_us":22,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":24960,"update_count":2500}
I20260812 06:18:51.735464  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:51.779460  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.044s	user 0.030s	sys 0.012s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":19316,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:51.779986  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:51.934054  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.154s	user 0.102s	sys 0.041s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20713153,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":578,"lbm_read_time_us":9048,"lbm_reads_lt_1ms":467,"lbm_write_time_us":24763,"lbm_writes_lt_1ms":443,"mutex_wait_us":320,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":6016,"update_count":2000}
I20260812 06:18:51.937144  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:51.980520  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.043s	user 0.021s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19423,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:51.980970  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:51.991642  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3699,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:51.992240  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:52.175864  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.183s	user 0.133s	sys 0.037s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":167,"lbm_read_time_us":11777,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26531,"lbm_writes_lt_1ms":543,"mutex_wait_us":23,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":100992,"update_count":2500}
I20260812 06:18:52.176386  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:52.219676  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.043s	user 0.014s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":17245,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:52.220203  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:52.230414  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.010s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3984,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:52.231156  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:52.392387  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.161s	user 0.122s	sys 0.027s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":145,"lbm_read_time_us":9196,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28884,"lbm_writes_lt_1ms":543,"mutex_wait_us":32,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":11392,"update_count":2500}
I20260812 06:18:52.392994  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:52.445786  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.053s	user 0.028s	sys 0.019s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":22195,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:52.446398  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:52.456597  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3714,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:52.457192  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushMRSOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:52.484560  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushMRSOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.027s	user 0.025s	sys 0.000s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":36,"dirs.run_cpu_time_us":210,"dirs.run_wall_time_us":1281,"drs_written":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1370,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:18:52.485179  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling LogGCOp(82d94480b76445009f53b295c4148ad5): free 121006441 bytes of WAL
I20260812 06:18:52.485432  7838 log_reader.cc:385] T 82d94480b76445009f53b295c4148ad5: removed 12 log segments from log reader
I20260812 06:18:52.485495  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000003 (ops 12-16)
I20260812 06:18:52.485544  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000004 (ops 17-21)
I20260812 06:18:52.485579  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000005 (ops 22-26)
I20260812 06:18:52.485612  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000006 (ops 27-31)
I20260812 06:18:52.485646  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000007 (ops 32-36)
I20260812 06:18:52.485677  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000008 (ops 37-41)
I20260812 06:18:52.485705  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000009 (ops 42-46)
I20260812 06:18:52.485733  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000010 (ops 47-50)
I20260812 06:18:52.485760  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000011 (ops 51-55)
I20260812 06:18:52.485791  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000012 (ops 56-60)
I20260812 06:18:52.485823  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000013 (ops 61-65)
I20260812 06:18:52.485852  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000014 (ops 66-70)
I20260812 06:18:52.511842  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: LogGCOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.026s	user 0.000s	sys 0.025s Metrics: {}
I20260812 06:18:52.512199  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5): 463 bytes on disk
I20260812 06:18:52.512578  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":49,"lbm_reads_lt_1ms":4}
I20260812 06:18:52.513016  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=3.181125
I20260812 06:18:52.534178  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.021s	user 0.008s	sys 0.008s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":3869,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:52.534554  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:52.543361  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.009s	user 0.006s	sys 0.001s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3244,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:52.543701  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:52.757208  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.213s	user 0.140s	sys 0.073s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020738,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":71,"lbm_read_time_us":14286,"lbm_reads_lt_1ms":774,"lbm_write_time_us":36514,"lbm_writes_lt_1ms":743,"mutex_wait_us":44,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":16640,"thread_start_us":82,"threads_started":1,"update_count":3500}
I20260812 06:18:52.757742  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=15.087375
I20260812 06:18:52.800513  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.043s	user 0.027s	sys 0.013s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":18438,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:18:52.801007  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:52.813859  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4845,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:52.814316  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:52.974478  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.160s	user 0.118s	sys 0.041s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815670,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1106,"lbm_read_time_us":11703,"lbm_reads_lt_1ms":568,"lbm_write_time_us":27275,"lbm_writes_lt_1ms":543,"mutex_wait_us":354,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3328,"update_count":2500}
I20260812 06:18:52.974943  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:53.035344  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.060s	user 0.027s	sys 0.032s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22888,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:18:53.035930  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:53.045753  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.010s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3724,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:53.046319  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:53.223671  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.177s	user 0.117s	sys 0.051s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":240,"lbm_read_time_us":12465,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28040,"lbm_writes_lt_1ms":543,"mutex_wait_us":57,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":4736,"update_count":2500}
I20260812 06:18:53.224184  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:53.282366  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.058s	user 0.033s	sys 0.017s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":17810,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:53.282941  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:53.293466  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.010s	user 0.005s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4051,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:53.293956  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:53.477098  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.183s	user 0.099s	sys 0.076s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":214,"lbm_read_time_us":11845,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31986,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":542,"mutex_wait_us":18,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6400,"update_count":2500}
I20260812 06:18:53.477617  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:53.530345  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.053s	user 0.028s	sys 0.024s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":21287,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:53.530844  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:53.556370  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.025s	user 0.008s	sys 0.012s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4658,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:53.556903  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:53.722605  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.166s	user 0.122s	sys 0.044s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815685,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":314,"lbm_read_time_us":12943,"lbm_reads_lt_1ms":564,"lbm_write_time_us":27601,"lbm_writes_lt_1ms":543,"mutex_wait_us":47,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10624,"update_count":2500}
I20260812 06:18:53.723572  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:53.777441  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.054s	user 0.030s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":26247,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:18:53.777885  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=3.181125
I20260812 06:18:53.798130  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.020s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4348807,"delete_count":0,"lbm_write_time_us":4915,"lbm_writes_lt_1ms":109,"reinsert_count":0,"update_count":530}
I20260812 06:18:53.798637  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:53.816154  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.017s	user 0.001s	sys 0.014s Metrics: {"bytes_written":3856509,"delete_count":0,"lbm_write_time_us":3336,"lbm_writes_lt_1ms":97,"reinsert_count":0,"update_count":470}
I20260812 06:18:53.816653  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushMRSOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:53.853053  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushMRSOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.036s	user 0.020s	sys 0.004s Metrics: {"bytes_written":1152514,"cfile_init":1,"dirs.queue_time_us":59,"dirs.run_cpu_time_us":205,"dirs.run_wall_time_us":1276,"drs_written":1,"lbm_read_time_us":35,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1330,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:18:53.853713  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling LogGCOp(82d94480b76445009f53b295c4148ad5): free 115943124 bytes of WAL
I20260812 06:18:53.853931  7838 log_reader.cc:385] T 82d94480b76445009f53b295c4148ad5: removed 11 log segments from log reader
I20260812 06:18:53.853981  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000015 (ops 71-75)
I20260812 06:18:53.854008  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000016 (ops 76-80)
I20260812 06:18:53.854039  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000017 (ops 81-85)
I20260812 06:18:53.854070  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000018 (ops 86-90)
I20260812 06:18:53.854102  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000019 (ops 91-95)
I20260812 06:18:53.854135  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000020 (ops 96-100)
I20260812 06:18:53.854189  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000021 (ops 101-105)
I20260812 06:18:53.854219  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000022 (ops 106-110)
I20260812 06:18:53.854259  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000023 (ops 111-115)
I20260812 06:18:53.854292  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000024 (ops 116-120)
I20260812 06:18:53.854323  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000025 (ops 121-125)
I20260812 06:18:53.874361  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: LogGCOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.020s	user 0.001s	sys 0.019s Metrics: {}
I20260812 06:18:53.874698  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5): 446 bytes on disk
I20260812 06:18:53.876063  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4}
I20260812 06:18:53.876523  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=3.181125
I20260812 06:18:53.897713  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.021s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4348807,"delete_count":0,"lbm_write_time_us":4714,"lbm_writes_lt_1ms":109,"reinsert_count":0,"update_count":530}
I20260812 06:18:53.898219  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:53.907065  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.009s	user 0.007s	sys 0.001s Metrics: {"bytes_written":3856509,"delete_count":0,"lbm_write_time_us":3276,"lbm_writes_lt_1ms":97,"reinsert_count":0,"update_count":470}
I20260812 06:18:53.907618  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:54.150408  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.243s	user 0.182s	sys 0.060s Metrics: {"cfile_cache_miss":835,"cfile_cache_miss_bytes":37123272,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":597,"lbm_read_time_us":17952,"lbm_reads_lt_1ms":875,"lbm_write_time_us":42134,"lbm_writes_lt_1ms":843,"mutex_wait_us":1021,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":14080,"thread_start_us":79,"threads_started":1,"update_count":4000}
I20260812 06:18:54.151079  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=18.063937
I20260812 06:18:54.201659  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.050s	user 0.027s	sys 0.020s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":21650,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:54.202310  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:54.226954  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.024s	user 0.004s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5442,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:54.227384  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:54.237529  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.010s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3962,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:54.237969  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:54.418352  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.180s	user 0.136s	sys 0.044s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":33020628,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":496,"lbm_read_time_us":12752,"lbm_reads_lt_1ms":773,"lbm_write_time_us":37486,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":2176,"update_count":3500}
I20260812 06:18:54.419029  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:54.466454  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.047s	user 0.021s	sys 0.025s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":18813,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:54.466905  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:54.490860  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.024s	user 0.011s	sys 0.005s Metrics: {"bytes_written":4225735,"delete_count":0,"lbm_write_time_us":6309,"lbm_writes_lt_1ms":106,"mutex_wait_us":94,"reinsert_count":0,"update_count":515}
I20260812 06:18:54.491369  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:54.500818  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.009s	user 0.000s	sys 0.007s Metrics: {"bytes_written":3979584,"delete_count":0,"lbm_write_time_us":3485,"lbm_writes_lt_1ms":100,"reinsert_count":0,"update_count":485}
I20260812 06:18:54.501413  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:54.654569  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.153s	user 0.137s	sys 0.015s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28918215,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":202,"lbm_read_time_us":12544,"lbm_reads_lt_1ms":673,"lbm_write_time_us":29796,"lbm_writes_lt_1ms":643,"mutex_wait_us":31,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":17536,"update_count":3000}
I20260812 06:18:54.655176  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=14.095187
I20260812 06:18:54.701742  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.046s	user 0.034s	sys 0.008s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":19714,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:54.702304  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:54.717332  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.015s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5541,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:54.717806  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:54.862865  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.145s	user 0.098s	sys 0.043s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815682,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":775,"lbm_read_time_us":10085,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27032,"lbm_writes_lt_1ms":543,"mutex_wait_us":268,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2048,"update_count":2500}
I20260812 06:18:54.863399  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=12.110812
I20260812 06:18:54.901207  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.038s	user 0.026s	sys 0.009s Metrics: {"bytes_written":13702314,"delete_count":0,"lbm_write_time_us":16782,"lbm_writes_lt_1ms":337,"reinsert_count":0,"update_count":1670}
I20260812 06:18:54.901841  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=1.196750
I20260812 06:18:54.912177  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":2707805,"delete_count":0,"lbm_write_time_us":3504,"lbm_writes_lt_1ms":69,"reinsert_count":0,"update_count":330}
I20260812 06:18:54.912925  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:55.054909  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.142s	user 0.101s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20713242,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":76,"lbm_read_time_us":10425,"lbm_reads_lt_1ms":464,"lbm_write_time_us":22853,"lbm_writes_lt_1ms":443,"mutex_wait_us":37,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":11392,"update_count":2000}
I20260812 06:18:55.055534  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=11.118625
I20260812 06:18:55.090955  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.035s	user 0.031s	sys 0.003s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":14576,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:55.091575  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:55.107753  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.016s	user 0.012s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5384,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:55.108281  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushMRSOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:55.154726  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushMRSOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.046s	user 0.031s	sys 0.001s Metrics: {"bytes_written":1193505,"cfile_init":1,"dirs.queue_time_us":47,"dirs.run_cpu_time_us":181,"dirs.run_wall_time_us":1246,"drs_written":1,"lbm_read_time_us":56,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2100,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:18:55.155591  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=3.181125
I20260812 06:18:55.169339  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.014s	user 0.004s	sys 0.006s Metrics: {"bytes_written":4348807,"delete_count":0,"lbm_write_time_us":4291,"lbm_writes_lt_1ms":109,"reinsert_count":0,"update_count":530}
I20260812 06:18:55.169737  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling LogGCOp(82d94480b76445009f53b295c4148ad5): free 120553652 bytes of WAL
I20260812 06:18:55.169941  7838 log_reader.cc:385] T 82d94480b76445009f53b295c4148ad5: removed 12 log segments from log reader
I20260812 06:18:55.169986  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000026 (ops 126-130)
I20260812 06:18:55.170013  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000027 (ops 131-134)
I20260812 06:18:55.170044  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000028 (ops 135-139)
I20260812 06:18:55.170076  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000029 (ops 140-144)
I20260812 06:18:55.170107  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000030 (ops 145-149)
I20260812 06:18:55.170140  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000031 (ops 150-154)
I20260812 06:18:55.170197  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000032 (ops 155-158)
I20260812 06:18:55.170229  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000033 (ops 159-163)
I20260812 06:18:55.170270  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000034 (ops 164-168)
I20260812 06:18:55.170302  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000035 (ops 169-173)
I20260812 06:18:55.170336  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000036 (ops 174-178)
I20260812 06:18:55.170367  7838 log.cc:1079] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: Deleting log segment in path: /tmp/dist-test-taskRw5wit/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515525617475-7314-0/minicluster-data/ts-0-root/wals/82d94480b76445009f53b295c4148ad5/wal-000000037 (ops 179-183)
I20260812 06:18:55.191396  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: LogGCOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.021s	user 0.000s	sys 0.020s Metrics: {}
I20260812 06:18:55.191769  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5): 462 bytes on disk
I20260812 06:18:55.192282  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: UndoDeltaBlockGCOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":68,"lbm_reads_lt_1ms":4}
I20260812 06:18:55.192843  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:55.209631  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.017s	user 0.010s	sys 0.001s Metrics: {"bytes_written":3856509,"delete_count":0,"lbm_write_time_us":4590,"lbm_writes_lt_1ms":97,"reinsert_count":0,"update_count":470}
I20260812 06:18:55.210074  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:55.219431  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.009s	user 0.007s	sys 0.001s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3491,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:55.219810  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:55.452755  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.233s	user 0.156s	sys 0.071s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":33020853,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":94,"lbm_read_time_us":15345,"lbm_reads_lt_1ms":775,"lbm_write_time_us":38153,"lbm_writes_lt_1ms":743,"mutex_wait_us":41,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":6656,"thread_start_us":79,"threads_started":1,"update_count":3500}
I20260812 06:18:55.453328  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=18.063937
I20260812 06:18:55.513784  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.060s	user 0.023s	sys 0.023s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":22747,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:55.514326  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5): perf score=2.188937
I20260812 06:18:55.523911  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: FlushDeltaMemStoresOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3650,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:55.524287  7939 maintenance_manager.cc:419] P 1c25c8517efd42158059821716069aa9: Scheduling MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5): perf score=1.000000
I20260812 06:18:55.554040  7314 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.550s	user 1.666s	sys 0.157s
I20260812 06:18:55.638370  7314 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.084s	user 0.003s	sys 0.000s
I20260812 06:18:55.638924  7314 tablet_server.cc:179] TabletServer@127.7.36.129:0 shutting down...
I20260812 06:18:55.694576  7838 maintenance_manager.cc:643] P 1c25c8517efd42158059821716069aa9: MajorDeltaCompactionOp(82d94480b76445009f53b295c4148ad5) complete. Timing: real 0.170s	user 0.122s	sys 0.048s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918098,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":577,"lbm_read_time_us":13633,"lbm_reads_lt_1ms":668,"lbm_write_time_us":27881,"lbm_writes_lt_1ms":643,"mutex_wait_us":254,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":6272,"update_count":3000}
I20260812 06:18:55.695111  7314 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:55.695365  7314 tablet_replica.cc:333] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9: stopping tablet replica
I20260812 06:18:55.695480  7314 raft_consensus.cc:2243] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:55.695631  7314 raft_consensus.cc:2272] T 82d94480b76445009f53b295c4148ad5 P 1c25c8517efd42158059821716069aa9 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:55.709971  7314 tablet_server.cc:196] TabletServer@127.7.36.129:0 shutdown complete.
I20260812 06:18:55.746263  7314 master.cc:562] Master@127.7.36.190:35469 shutting down...
I20260812 06:18:55.749279  7314 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:55.749472  7314 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:55.749536  7314 tablet_replica.cc:333] T 00000000000000000000000000000000 P 08606b868f914f75938e1512a4bae16e: stopping tablet replica
I20260812 06:18:55.761546  7314 master.cc:584] Master@127.7.36.190:35469 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (4988 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10210 ms total)

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