[==========] 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:25.164839 26174 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.25.143.190:38817
I20260812 06:18:25.165841 26174 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:25.166435 26174 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:25.172374 26183 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:25.172411 26180 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:25.172649 26181 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:25.172626 26174 server_base.cc:1061] running on GCE node
I20260812 06:18:25.173081 26174 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:25.173204 26174 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:25.173251 26174 hybrid_clock.cc:648] HybridClock initialized: now 1786515505173249 us; error 0 us; skew 500 ppm
I20260812 06:18:25.174959 26174 webserver.cc:533] Webserver started at http://127.25.143.190:33615/ using document root <none> and password file <none>
I20260812 06:18:25.175488 26174 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:25.175572 26174 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:25.175800 26174 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:25.177438 26174 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/master-0-root/instance:
uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb"
format_stamp: "Formatted at 2026-08-12 06:18:25 on dist-test-slave-gmjp"
I20260812 06:18:25.180768 26174 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:18:25.183401 26191 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:25.184736 26174 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:18:25.184857 26174 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/master-0-root
uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb"
format_stamp: "Formatted at 2026-08-12 06:18:25 on dist-test-slave-gmjp"
I20260812 06:18:25.184976 26174 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-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:25.196906 26174 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:25.197399 26174 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:25.197525 26174 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:25.204828 26284 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.143.190:38817 every 8 connection(s)
I20260812 06:18:25.204844 26174 rpc_server.cc:307] RPC server started. Bound to: 127.25.143.190:38817
I20260812 06:18:25.207036 26285 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:25.212383 26285 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb: Bootstrap starting.
I20260812 06:18:25.214640 26285 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:25.215492 26285 log.cc:826] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb: Log is configured to *not* fsync() on all Append() calls
I20260812 06:18:25.217140 26285 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb: No bootstrap required, opened a new log
I20260812 06:18:25.219807 26285 raft_consensus.cc:359] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb" member_type: VOTER }
I20260812 06:18:25.219973 26285 raft_consensus.cc:385] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:25.220022 26285 raft_consensus.cc:740] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: ac1a1de7b63b468e9c926e4f0fc64ccb, State: Initialized, Role: FOLLOWER
I20260812 06:18:25.220662 26285 consensus_queue.cc:260] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [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: "ac1a1de7b63b468e9c926e4f0fc64ccb" member_type: VOTER }
I20260812 06:18:25.220804 26285 raft_consensus.cc:399] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:25.220851 26285 raft_consensus.cc:493] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:25.220940 26285 raft_consensus.cc:3060] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:25.221635 26285 raft_consensus.cc:515] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb" member_type: VOTER }
I20260812 06:18:25.222010 26285 leader_election.cc:304] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [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: ac1a1de7b63b468e9c926e4f0fc64ccb; no voters: 
I20260812 06:18:25.222260 26285 leader_election.cc:290] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:25.222421 26289 raft_consensus.cc:2804] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:25.222697 26289 raft_consensus.cc:697] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 1 LEADER]: Becoming Leader. State: Replica: ac1a1de7b63b468e9c926e4f0fc64ccb, State: Running, Role: LEADER
I20260812 06:18:25.223064 26289 consensus_queue.cc:237] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [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: "ac1a1de7b63b468e9c926e4f0fc64ccb" member_type: VOTER }
I20260812 06:18:25.223250 26285 sys_catalog.cc:565] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:25.225011 26292 sys_catalog.cc:455] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [sys.catalog]: SysCatalogTable state changed. Reason: New leader ac1a1de7b63b468e9c926e4f0fc64ccb. Latest consensus state: current_term: 1 leader_uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb" member_type: VOTER } }
I20260812 06:18:25.225153 26292 sys_catalog.cc:458] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:25.225034 26291 sys_catalog.cc:455] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ac1a1de7b63b468e9c926e4f0fc64ccb" member_type: VOTER } }
I20260812 06:18:25.225404 26291 sys_catalog.cc:458] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:25.225512 26320 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:25.225566 26174 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:18:25.227707 26320 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:25.232270 26320 catalog_manager.cc:1383] Generated new cluster ID: d4484cdb5ccc41a68ee6a7daca451b06
I20260812 06:18:25.232340 26320 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:25.250321 26320 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:25.251206 26320 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:25.268397 26320 catalog_manager.cc:6092] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb: Generated new TSK 0
I20260812 06:18:25.269127 26320 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:25.290426 26174 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:25.293128 26337 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:25.293133 26329 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:25.293265 26330 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:25.293704 26174 server_base.cc:1061] running on GCE node
I20260812 06:18:25.293905 26174 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:25.293954 26174 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:25.293977 26174 hybrid_clock.cc:648] HybridClock initialized: now 1786515505293977 us; error 0 us; skew 500 ppm
I20260812 06:18:25.294965 26174 webserver.cc:533] Webserver started at http://127.25.143.129:34261/ using document root <none> and password file <none>
I20260812 06:18:25.295145 26174 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:25.295208 26174 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:25.295290 26174 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:25.295753 26174 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/instance:
uuid: "7adab25408014c6d88356cbff656eb09"
format_stamp: "Formatted at 2026-08-12 06:18:25 on dist-test-slave-gmjp"
I20260812 06:18:25.297727 26174 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.000s	sys 0.003s
I20260812 06:18:25.298898 26344 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:25.299221 26174 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:18:25.299295 26174 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root
uuid: "7adab25408014c6d88356cbff656eb09"
format_stamp: "Formatted at 2026-08-12 06:18:25 on dist-test-slave-gmjp"
I20260812 06:18:25.299396 26174 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-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:25.317833 26174 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:25.318602 26174 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:25.319166 26174 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:25.320029 26174 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:25.320080 26174 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:25.320156 26174 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:25.320222 26174 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:25.327037 26174 rpc_server.cc:307] RPC server started. Bound to: 127.25.143.129:38993
I20260812 06:18:25.327165 26455 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.143.129:38993 every 8 connection(s)
I20260812 06:18:25.339671 26459 heartbeater.cc:344] Connected to a master server at 127.25.143.190:38817
I20260812 06:18:25.339937 26459 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:25.340490 26459 heartbeater.cc:507] Master 127.25.143.190:38817 requested a full tablet report, sending...
I20260812 06:18:25.341878 26221 ts_manager.cc:194] Registered new tserver with Master: 7adab25408014c6d88356cbff656eb09 (127.25.143.129:38993)
I20260812 06:18:25.342350 26174 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.014608073s
I20260812 06:18:25.343118 26221 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:49552
I20260812 06:18:25.353253 26221 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:49564:
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:25.367331 26392 tablet_service.cc:1511] Processing CreateTablet for tablet 893aa2eaaba443c88e50f5156fcc6773 (DEFAULT_TABLE table=heavy-update-compaction-test [id=56a2f19685604c3aa966c9f017d18ae9]), partition=
I20260812 06:18:25.367776 26392 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 893aa2eaaba443c88e50f5156fcc6773. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:25.370335 26479 tablet_bootstrap.cc:492] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Bootstrap starting.
I20260812 06:18:25.371292 26479 tablet_bootstrap.cc:654] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:25.372825 26479 tablet_bootstrap.cc:492] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: No bootstrap required, opened a new log
I20260812 06:18:25.372958 26479 ts_tablet_manager.cc:1403] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:18:25.373488 26479 raft_consensus.cc:359] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7adab25408014c6d88356cbff656eb09" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 38993 } }
I20260812 06:18:25.373589 26479 raft_consensus.cc:385] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:25.373613 26479 raft_consensus.cc:740] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 7adab25408014c6d88356cbff656eb09, State: Initialized, Role: FOLLOWER
I20260812 06:18:25.373762 26479 consensus_queue.cc:260] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [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: "7adab25408014c6d88356cbff656eb09" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 38993 } }
I20260812 06:18:25.373855 26479 raft_consensus.cc:399] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:25.373885 26479 raft_consensus.cc:493] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:25.374006 26479 raft_consensus.cc:3060] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:25.374760 26479 raft_consensus.cc:515] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7adab25408014c6d88356cbff656eb09" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 38993 } }
I20260812 06:18:25.374881 26479 leader_election.cc:304] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [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: 7adab25408014c6d88356cbff656eb09; no voters: 
I20260812 06:18:25.375180 26479 leader_election.cc:290] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:25.375322 26483 raft_consensus.cc:2804] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:25.375558 26479 ts_tablet_manager.cc:1434] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:18:25.375622 26483 raft_consensus.cc:697] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 1 LEADER]: Becoming Leader. State: Replica: 7adab25408014c6d88356cbff656eb09, State: Running, Role: LEADER
I20260812 06:18:25.375756 26459 heartbeater.cc:499] Master 127.25.143.190:38817 was elected leader, sending a full tablet report...
I20260812 06:18:25.375885 26483 consensus_queue.cc:237] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [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: "7adab25408014c6d88356cbff656eb09" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 38993 } }
I20260812 06:18:25.378715 26221 catalog_manager.cc:5719] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 reported cstate change: term changed from 0 to 1, leader changed from <none> to 7adab25408014c6d88356cbff656eb09 (127.25.143.129). New cstate: current_term: 1 leader_uuid: "7adab25408014c6d88356cbff656eb09" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "7adab25408014c6d88356cbff656eb09" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 38993 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:25.448350 26174 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.062s	user 0.029s	sys 0.004s
I20260812 06:18:25.578186 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773): perf score=19.054940
I20260812 06:18:25.791987 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.213s	user 0.159s	sys 0.048s Metrics: {"bytes_written":16409901,"cfile_init":1,"compiler_manager_pool.queue_time_us":205,"delete_count":0,"dirs.queue_time_us":88,"dirs.run_cpu_time_us":238,"dirs.run_wall_time_us":733,"drs_written":1,"lbm_read_time_us":90,"lbm_reads_lt_1ms":4,"lbm_write_time_us":54982,"lbm_writes_lt_1ms":857,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"thread_start_us":131,"threads_started":1,"update_count":2000}
I20260812 06:18:25.793327 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling LogGCOp(893aa2eaaba443c88e50f5156fcc6773): free 20743880 bytes of WAL
I20260812 06:18:25.793717 26352 log_reader.cc:385] T 893aa2eaaba443c88e50f5156fcc6773: removed 2 log segments from log reader
I20260812 06:18:25.793869 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000001 (ops 1-6)
I20260812 06:18:25.793991 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000002 (ops 7-11)
I20260812 06:18:25.800256 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: LogGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.007s	user 0.000s	sys 0.006s Metrics: {}
I20260812 06:18:25.800697 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling UndoDeltaBlockGCOp(893aa2eaaba443c88e50f5156fcc6773): 16411398 bytes on disk
I20260812 06:18:25.801405 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: UndoDeltaBlockGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":72,"lbm_reads_lt_1ms":4}
I20260812 06:18:25.801970 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=4.173312
I20260812 06:18:25.824510 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.022s	user 0.010s	sys 0.012s Metrics: {"bytes_written":5866705,"delete_count":0,"lbm_write_time_us":9242,"lbm_writes_lt_1ms":146,"reinsert_count":0,"update_count":715}
I20260812 06:18:25.825054 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.196750
I20260812 06:18:25.836527 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.011s	user 0.007s	sys 0.004s Metrics: {"bytes_written":2338579,"delete_count":0,"lbm_write_time_us":3949,"lbm_writes_lt_1ms":60,"reinsert_count":0,"update_count":285}
I20260812 06:18:25.837090 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:26.051944 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.215s	user 0.135s	sys 0.077s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877179,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":664,"lbm_read_time_us":15289,"lbm_reads_lt_1ms":665,"lbm_write_time_us":37108,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":896,"thread_start_us":314,"threads_started":5,"update_count":3000}
I20260812 06:18:26.052866 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=17.071750
I20260812 06:18:26.103346 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.050s	user 0.025s	sys 0.020s Metrics: {"bytes_written":18666228,"delete_count":0,"lbm_write_time_us":21074,"lbm_writes_lt_1ms":458,"reinsert_count":0,"update_count":2275}
I20260812 06:18:26.103876 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.196750
I20260812 06:18:26.117787 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.014s	user 0.008s	sys 0.000s Metrics: {"bytes_written":2256534,"delete_count":0,"lbm_write_time_us":3536,"lbm_writes_lt_1ms":58,"reinsert_count":0,"update_count":275}
I20260812 06:18:26.118193 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:26.127714 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.009s	user 0.003s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3769,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:26.128119 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:26.315927 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.188s	user 0.138s	sys 0.049s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877167,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":803,"lbm_read_time_us":12069,"lbm_reads_lt_1ms":673,"lbm_write_time_us":33728,"lbm_writes_lt_1ms":643,"mutex_wait_us":346,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2176,"update_count":3000}
I20260812 06:18:26.316437 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:26.374585 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.058s	user 0.020s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22216,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:26.375125 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:26.386636 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4098,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:26.387118 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:26.560961 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.174s	user 0.105s	sys 0.067s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":883,"lbm_read_time_us":12110,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30244,"lbm_writes_lt_1ms":543,"mutex_wait_us":293,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":384,"update_count":2500}
I20260812 06:18:26.561440 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:26.622429 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.061s	user 0.028s	sys 0.019s Metrics: {"bytes_written":16409898,"delete_count":0,"lbm_write_time_us":21113,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:26.622893 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:26.634595 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4190,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:26.635109 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:26.802563 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.167s	user 0.110s	sys 0.053s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774685,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":565,"lbm_read_time_us":13020,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26719,"lbm_writes_lt_1ms":543,"mutex_wait_us":301,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5248,"update_count":2500}
I20260812 06:18:26.803179 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:26.861435 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.058s	user 0.034s	sys 0.022s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":20620,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:26.862035 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:26.878568 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.016s	user 0.013s	sys 0.001s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6194,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:26.879138 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:27.043360 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.164s	user 0.115s	sys 0.049s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774689,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":854,"lbm_read_time_us":13294,"lbm_reads_lt_1ms":572,"lbm_write_time_us":26866,"lbm_writes_lt_1ms":543,"mutex_wait_us":335,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":12928,"update_count":2500}
I20260812 06:18:27.043989 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=10.126437
I20260812 06:18:27.081499 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.037s	user 0.017s	sys 0.019s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16477,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:27.082018 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:27.100361 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.018s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5248,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:27.100879 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:27.148816 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.048s	user 0.028s	sys 0.001s Metrics: {"bytes_written":1316413,"cfile_init":1,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":269,"dirs.run_wall_time_us":1349,"drs_written":1,"lbm_read_time_us":51,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1656,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:18:27.149923 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling UndoDeltaBlockGCOp(893aa2eaaba443c88e50f5156fcc6773): 492 bytes on disk
I20260812 06:18:27.150437 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: UndoDeltaBlockGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":91,"lbm_reads_lt_1ms":4}
I20260812 06:18:27.150962 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=3.181125
I20260812 06:18:27.165597 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.015s	user 0.010s	sys 0.001s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":4419,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:27.166126 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling LogGCOp(893aa2eaaba443c88e50f5156fcc6773): free 132571327 bytes of WAL
I20260812 06:18:27.166415 26352 log_reader.cc:385] T 893aa2eaaba443c88e50f5156fcc6773: removed 13 log segments from log reader
I20260812 06:18:27.166481 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000003 (ops 12-16)
I20260812 06:18:27.166522 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000004 (ops 17-21)
I20260812 06:18:27.166550 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000005 (ops 22-26)
I20260812 06:18:27.166572 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000006 (ops 27-31)
I20260812 06:18:27.166594 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000007 (ops 32-36)
I20260812 06:18:27.166615 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000008 (ops 37-41)
I20260812 06:18:27.166635 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000009 (ops 42-46)
I20260812 06:18:27.166658 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000010 (ops 47-51)
I20260812 06:18:27.166693 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000011 (ops 52-56)
I20260812 06:18:27.166723 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000012 (ops 57-60)
I20260812 06:18:27.166749 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000013 (ops 61-65)
I20260812 06:18:27.166774 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000014 (ops 66-70)
I20260812 06:18:27.166805 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000015 (ops 71-74)
I20260812 06:18:27.200837 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: LogGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.034s	user 0.002s	sys 0.031s Metrics: {}
I20260812 06:18:27.201397 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:27.213469 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.012s	user 0.005s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4152,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:27.213949 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:27.409871 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.196s	user 0.115s	sys 0.077s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877329,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":247,"lbm_read_time_us":14282,"lbm_reads_lt_1ms":666,"lbm_write_time_us":33337,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":8192,"thread_start_us":80,"threads_started":1,"update_count":3000}
I20260812 06:18:27.410861 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=15.087375
I20260812 06:18:27.465072 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.054s	user 0.031s	sys 0.016s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":20730,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:18:27.465497 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:27.477403 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4036,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:27.477823 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:27.487228 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.009s	user 0.004s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3638,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:27.487605 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:27.678790 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.191s	user 0.115s	sys 0.076s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877206,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":331,"lbm_read_time_us":13892,"lbm_reads_lt_1ms":673,"lbm_write_time_us":33346,"lbm_writes_lt_1ms":643,"mutex_wait_us":20,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":5376,"update_count":3000}
I20260812 06:18:27.679350 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:27.725026 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.046s	user 0.019s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19981,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:27.725510 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:27.886705 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.158s	user 0.111s	sys 0.036s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672157,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":909,"lbm_read_time_us":9232,"lbm_reads_lt_1ms":463,"lbm_write_time_us":25403,"lbm_writes_lt_1ms":443,"mutex_wait_us":313,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2000}
I20260812 06:18:27.887290 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:27.939286 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.052s	user 0.024s	sys 0.019s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21322,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:27.939810 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:27.950955 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3954,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:27.951412 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:28.131354 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.180s	user 0.123s	sys 0.047s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":680,"lbm_read_time_us":9282,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28705,"lbm_writes_lt_1ms":543,"mutex_wait_us":19,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5888,"update_count":2500}
I20260812 06:18:28.132083 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:28.186968 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.055s	user 0.035s	sys 0.013s Metrics: {"bytes_written":16409897,"delete_count":0,"lbm_write_time_us":22573,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:28.187449 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:28.199661 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.012s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4409,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:28.200137 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:28.367158 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.167s	user 0.129s	sys 0.027s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":942,"lbm_read_time_us":10524,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32962,"lbm_writes_lt_1ms":543,"mutex_wait_us":259,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":4480,"update_count":2500}
I20260812 06:18:28.367676 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:28.418946 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.051s	user 0.031s	sys 0.016s Metrics: {"bytes_written":16409898,"delete_count":0,"lbm_write_time_us":22213,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:28.419591 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:28.431908 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.012s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4136,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:28.432583 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:28.586676 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.154s	user 0.128s	sys 0.025s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774685,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":954,"lbm_read_time_us":10448,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31149,"lbm_writes_lt_1ms":543,"mutex_wait_us":259,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":4352,"update_count":2500}
I20260812 06:18:28.587320 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=11.118625
I20260812 06:18:28.631656 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.044s	user 0.020s	sys 0.020s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":20402,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":312,"reinsert_count":0,"update_count":1550}
I20260812 06:18:28.632455 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:28.647397 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.015s	user 0.012s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5289,"lbm_writes_lt_1ms":93,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":450}
I20260812 06:18:28.647903 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:28.698800 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.051s	user 0.027s	sys 0.000s Metrics: {"bytes_written":1275447,"cfile_init":1,"dirs.queue_time_us":76,"dirs.run_cpu_time_us":276,"dirs.run_wall_time_us":1147,"drs_written":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1839,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:18:28.699615 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling LogGCOp(893aa2eaaba443c88e50f5156fcc6773): free 120553398 bytes of WAL
I20260812 06:18:28.699863 26352 log_reader.cc:385] T 893aa2eaaba443c88e50f5156fcc6773: removed 12 log segments from log reader
I20260812 06:18:28.699916 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000016 (ops 75-79)
I20260812 06:18:28.699966 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000017 (ops 80-84)
I20260812 06:18:28.700063 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000018 (ops 85-88)
I20260812 06:18:28.700126 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000019 (ops 89-93)
I20260812 06:18:28.700189 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000020 (ops 94-98)
I20260812 06:18:28.700232 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000021 (ops 99-103)
I20260812 06:18:28.700271 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000022 (ops 104-108)
I20260812 06:18:28.700316 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000023 (ops 109-113)
I20260812 06:18:28.700356 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000024 (ops 114-118)
I20260812 06:18:28.700395 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000025 (ops 119-123)
I20260812 06:18:28.700434 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000026 (ops 124-128)
I20260812 06:18:28.700474 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000027 (ops 129-132)
I20260812 06:18:28.726482 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: LogGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.027s	user 0.006s	sys 0.018s Metrics: {}
I20260812 06:18:28.726890 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling UndoDeltaBlockGCOp(893aa2eaaba443c88e50f5156fcc6773): 482 bytes on disk
I20260812 06:18:28.727392 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: UndoDeltaBlockGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":69,"lbm_reads_lt_1ms":4}
I20260812 06:18:28.727916 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=7.149875
I20260812 06:18:28.757352 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.029s	user 0.009s	sys 0.019s Metrics: {"bytes_written":8615324,"delete_count":0,"lbm_write_time_us":12484,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:18:28.757871 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling LogGCOp(893aa2eaaba443c88e50f5156fcc6773): free 8767140 bytes of WAL
I20260812 06:18:28.758159 26352 log_reader.cc:385] T 893aa2eaaba443c88e50f5156fcc6773: removed 1 log segments from log reader
I20260812 06:18:28.758221 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000028 (ops 133-137)
I20260812 06:18:28.760471 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: LogGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.002s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:18:28.760756 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:28.789865 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.029s	user 0.009s	sys 0.012s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4910,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:28.790680 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:29.019018 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.228s	user 0.155s	sys 0.070s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979735,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":735,"lbm_read_time_us":15042,"lbm_reads_lt_1ms":766,"lbm_write_time_us":41260,"lbm_writes_lt_1ms":743,"mutex_wait_us":62,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":768,"thread_start_us":99,"threads_started":1,"update_count":3500}
I20260812 06:18:29.019685 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=18.063937
I20260812 06:18:29.086874 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.067s	user 0.028s	sys 0.036s Metrics: {"bytes_written":20512314,"delete_count":0,"lbm_write_time_us":25780,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:29.087432 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:29.098208 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4167,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:29.098640 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:29.291949 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.193s	user 0.137s	sys 0.056s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877101,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":810,"lbm_read_time_us":15211,"lbm_reads_lt_1ms":672,"lbm_write_time_us":33113,"lbm_writes_lt_1ms":643,"mutex_wait_us":98,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3968,"update_count":3000}
I20260812 06:18:29.292623 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:29.349699 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.057s	user 0.029s	sys 0.019s Metrics: {"bytes_written":16409907,"delete_count":0,"lbm_write_time_us":22932,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:29.350214 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:29.360913 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.011s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4188,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:29.361564 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:29.542164 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.180s	user 0.116s	sys 0.058s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774694,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":876,"lbm_read_time_us":11771,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33633,"lbm_writes_lt_1ms":543,"mutex_wait_us":73,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":95104,"update_count":2500}
I20260812 06:18:29.542851 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:29.599205 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.056s	user 0.025s	sys 0.028s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":20977,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:29.599826 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:29.610400 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4161,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:29.610999 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:29.798650 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.187s	user 0.120s	sys 0.056s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":311,"lbm_read_time_us":12513,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31648,"lbm_writes_lt_1ms":543,"mutex_wait_us":25,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2500}
I20260812 06:18:29.799106 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:29.857769 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.058s	user 0.026s	sys 0.030s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":26281,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":400,"reinsert_count":0,"update_count":2000}
I20260812 06:18:29.858323 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:29.869179 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3961,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:29.869863 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:30.041808 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.172s	user 0.131s	sys 0.032s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":281,"lbm_read_time_us":11532,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27052,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:30.042531 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=14.095187
I20260812 06:18:30.104007 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.061s	user 0.030s	sys 0.016s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":24526,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:30.104789 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:30.120496 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.015s	user 0.012s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5680,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:30.124761 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:30.164377 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushMRSOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.037s	user 0.033s	sys 0.003s Metrics: {"bytes_written":1152512,"cfile_init":1,"dirs.queue_time_us":65,"dirs.run_cpu_time_us":178,"dirs.run_wall_time_us":1186,"drs_written":1,"lbm_read_time_us":62,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2202,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:18:30.165074 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling LogGCOp(893aa2eaaba443c88e50f5156fcc6773): free 115943422 bytes of WAL
I20260812 06:18:30.165297 26352 log_reader.cc:385] T 893aa2eaaba443c88e50f5156fcc6773: removed 11 log segments from log reader
I20260812 06:18:30.165343 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000029 (ops 138-142)
I20260812 06:18:30.165372 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000030 (ops 143-147)
I20260812 06:18:30.165436 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000031 (ops 148-152)
I20260812 06:18:30.165482 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000032 (ops 153-157)
I20260812 06:18:30.165524 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000033 (ops 158-162)
I20260812 06:18:30.165562 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000034 (ops 163-167)
I20260812 06:18:30.165601 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000035 (ops 168-172)
I20260812 06:18:30.165642 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000036 (ops 173-177)
I20260812 06:18:30.165680 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000037 (ops 178-182)
I20260812 06:18:30.165719 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000038 (ops 183-187)
I20260812 06:18:30.165757 26352 log.cc:1079] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/893aa2eaaba443c88e50f5156fcc6773/wal-000000039 (ops 188-192)
I20260812 06:18:30.191548 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: LogGCOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.026s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:18:30.191944 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:30.210397 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.018s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4316,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:30.210834 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=2.188937
I20260812 06:18:30.221290 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3914,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:30.221813 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773): perf score=1.000000
I20260812 06:18:30.345606 26174 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.897s	user 1.812s	sys 0.114s
I20260812 06:18:30.434382 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: MajorDeltaCompactionOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.211s	user 0.128s	sys 0.083s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979750,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":914,"lbm_read_time_us":14889,"lbm_reads_lt_1ms":770,"lbm_write_time_us":35649,"lbm_writes_lt_1ms":743,"mutex_wait_us":78,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":896,"thread_start_us":83,"threads_started":1,"update_count":3500}
I20260812 06:18:30.434916 26460 maintenance_manager.cc:419] P 7adab25408014c6d88356cbff656eb09: Scheduling FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773): perf score=10.126437
I20260812 06:18:30.443199 26174 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.097s	user 0.003s	sys 0.000s
I20260812 06:18:30.443938 26174 tablet_server.cc:179] TabletServer@127.25.143.129:0 shutting down...
I20260812 06:18:30.467212 26352 maintenance_manager.cc:643] P 7adab25408014c6d88356cbff656eb09: FlushDeltaMemStoresOp(893aa2eaaba443c88e50f5156fcc6773) complete. Timing: real 0.032s	user 0.014s	sys 0.015s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":14498,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:30.469303 26174 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:30.469699 26174 tablet_replica.cc:333] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09: stopping tablet replica
I20260812 06:18:30.469959 26174 raft_consensus.cc:2243] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:30.470201 26174 raft_consensus.cc:2272] T 893aa2eaaba443c88e50f5156fcc6773 P 7adab25408014c6d88356cbff656eb09 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:30.485695 26174 tablet_server.cc:196] TabletServer@127.25.143.129:0 shutdown complete.
I20260812 06:18:30.493099 26174 master.cc:562] Master@127.25.143.190:38817 shutting down...
I20260812 06:18:30.497375 26174 raft_consensus.cc:2243] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:30.497553 26174 raft_consensus.cc:2272] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:30.497643 26174 tablet_replica.cc:333] T 00000000000000000000000000000000 P ac1a1de7b63b468e9c926e4f0fc64ccb: stopping tablet replica
I20260812 06:18:30.510002 26174 master.cc:584] Master@127.25.143.190:38817 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5433 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:18:30.597997 26174 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.25.143.190:42235
I20260812 06:18:30.598402 26174 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:30.600548 26522 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:30.600610 26518 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:30.600556 26517 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:30.600703 26174 server_base.cc:1061] running on GCE node
I20260812 06:18:30.601001 26174 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:30.601043 26174 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:30.601058 26174 hybrid_clock.cc:648] HybridClock initialized: now 1786515510601059 us; error 0 us; skew 500 ppm
I20260812 06:18:30.601873 26174 webserver.cc:533] Webserver started at http://127.25.143.190:37801/ using document root <none> and password file <none>
I20260812 06:18:30.602072 26174 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:30.602137 26174 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:30.602218 26174 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:30.602596 26174 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/master-0-root/instance:
uuid: "f5abb1c40f0d481b9354cc471e663214"
format_stamp: "Formatted at 2026-08-12 06:18:30 on dist-test-slave-gmjp"
I20260812 06:18:30.604065 26174 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:18:30.605000 26529 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:30.605261 26174 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:18:30.605327 26174 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/master-0-root
uuid: "f5abb1c40f0d481b9354cc471e663214"
format_stamp: "Formatted at 2026-08-12 06:18:30 on dist-test-slave-gmjp"
I20260812 06:18:30.605417 26174 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-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:30.622872 26174 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:30.623293 26174 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:30.627691 26174 rpc_server.cc:307] RPC server started. Bound to: 127.25.143.190:42235
I20260812 06:18:30.630775 26637 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:30.633205 26633 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.143.190:42235 every 8 connection(s)
I20260812 06:18:30.642843 26637 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214: Bootstrap starting.
I20260812 06:18:30.643672 26637 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:30.644758 26637 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214: No bootstrap required, opened a new log
I20260812 06:18:30.645190 26637 raft_consensus.cc:359] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f5abb1c40f0d481b9354cc471e663214" member_type: VOTER }
I20260812 06:18:30.645278 26637 raft_consensus.cc:385] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:30.645337 26637 raft_consensus.cc:740] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: f5abb1c40f0d481b9354cc471e663214, State: Initialized, Role: FOLLOWER
I20260812 06:18:30.645527 26637 consensus_queue.cc:260] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [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: "f5abb1c40f0d481b9354cc471e663214" member_type: VOTER }
I20260812 06:18:30.645601 26637 raft_consensus.cc:399] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:30.645663 26637 raft_consensus.cc:493] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:30.645722 26637 raft_consensus.cc:3060] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:30.646407 26637 raft_consensus.cc:515] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f5abb1c40f0d481b9354cc471e663214" member_type: VOTER }
I20260812 06:18:30.646560 26637 leader_election.cc:304] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [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: f5abb1c40f0d481b9354cc471e663214; no voters: 
I20260812 06:18:30.646775 26637 leader_election.cc:290] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:30.646900 26641 raft_consensus.cc:2804] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:30.647157 26641 raft_consensus.cc:697] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 1 LEADER]: Becoming Leader. State: Replica: f5abb1c40f0d481b9354cc471e663214, State: Running, Role: LEADER
I20260812 06:18:30.647213 26637 sys_catalog.cc:565] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:30.647291 26641 consensus_queue.cc:237] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [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: "f5abb1c40f0d481b9354cc471e663214" member_type: VOTER }
I20260812 06:18:30.647761 26643 sys_catalog.cc:455] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "f5abb1c40f0d481b9354cc471e663214" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f5abb1c40f0d481b9354cc471e663214" member_type: VOTER } }
I20260812 06:18:30.647816 26644 sys_catalog.cc:455] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [sys.catalog]: SysCatalogTable state changed. Reason: New leader f5abb1c40f0d481b9354cc471e663214. Latest consensus state: current_term: 1 leader_uuid: "f5abb1c40f0d481b9354cc471e663214" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "f5abb1c40f0d481b9354cc471e663214" member_type: VOTER } }
I20260812 06:18:30.647918 26643 sys_catalog.cc:458] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:30.647940 26644 sys_catalog.cc:458] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:30.648442 26655 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:30.649466 26655 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:30.649679 26174 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:18:30.651252 26655 catalog_manager.cc:1383] Generated new cluster ID: 1e39165d07fb49c9b7c400aa9369e350
I20260812 06:18:30.651312 26655 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:30.672168 26655 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:30.672755 26655 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:30.678100 26655 catalog_manager.cc:6092] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214: Generated new TSK 0
I20260812 06:18:30.678287 26655 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:30.681874 26174 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:30.683625 26674 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:30.683660 26676 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:30.683717 26673 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:30.683761 26174 server_base.cc:1061] running on GCE node
I20260812 06:18:30.684029 26174 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:30.684093 26174 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:30.684119 26174 hybrid_clock.cc:648] HybridClock initialized: now 1786515510684119 us; error 0 us; skew 500 ppm
I20260812 06:18:30.684963 26174 webserver.cc:533] Webserver started at http://127.25.143.129:36427/ using document root <none> and password file <none>
I20260812 06:18:30.685137 26174 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:30.685206 26174 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:30.685283 26174 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:30.685686 26174 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/instance:
uuid: "628ccb2e25cf47bcaa694fb9a8d314fb"
format_stamp: "Formatted at 2026-08-12 06:18:30 on dist-test-slave-gmjp"
I20260812 06:18:30.687139 26174 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:18:30.688004 26682 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:30.688277 26174 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:18:30.688366 26174 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root
uuid: "628ccb2e25cf47bcaa694fb9a8d314fb"
format_stamp: "Formatted at 2026-08-12 06:18:30 on dist-test-slave-gmjp"
I20260812 06:18:30.688452 26174 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-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:30.706204 26174 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:30.706565 26174 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:30.706871 26174 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:30.707337 26174 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:30.707399 26174 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:30.707448 26174 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:30.707499 26174 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:30.711753 26174 rpc_server.cc:307] RPC server started. Bound to: 127.25.143.129:36561
I20260812 06:18:30.711786 26799 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.143.129:36561 every 8 connection(s)
I20260812 06:18:30.719195 26801 heartbeater.cc:344] Connected to a master server at 127.25.143.190:42235
I20260812 06:18:30.719312 26801 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:30.719552 26801 heartbeater.cc:507] Master 127.25.143.190:42235 requested a full tablet report, sending...
I20260812 06:18:30.720233 26563 ts_manager.cc:194] Registered new tserver with Master: 628ccb2e25cf47bcaa694fb9a8d314fb (127.25.143.129:36561)
I20260812 06:18:30.720906 26563 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:50042
I20260812 06:18:30.721038 26174 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.008799956s
I20260812 06:18:30.727782 26563 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:50050:
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:30.736059 26729 tablet_service.cc:1511] Processing CreateTablet for tablet cd8f5bfed58446a3b85725485dd00f7f (DEFAULT_TABLE table=heavy-update-compaction-test [id=92d3db586ef64146932e37f3f638f35e]), partition=
I20260812 06:18:30.736366 26729 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet cd8f5bfed58446a3b85725485dd00f7f. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:30.738154 26821 tablet_bootstrap.cc:492] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Bootstrap starting.
I20260812 06:18:30.739096 26821 tablet_bootstrap.cc:654] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:30.740089 26821 tablet_bootstrap.cc:492] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: No bootstrap required, opened a new log
I20260812 06:18:30.740212 26821 ts_tablet_manager.cc:1403] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:18:30.740586 26821 raft_consensus.cc:359] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "628ccb2e25cf47bcaa694fb9a8d314fb" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 36561 } }
I20260812 06:18:30.740670 26821 raft_consensus.cc:385] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:30.740692 26821 raft_consensus.cc:740] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 628ccb2e25cf47bcaa694fb9a8d314fb, State: Initialized, Role: FOLLOWER
I20260812 06:18:30.740834 26821 consensus_queue.cc:260] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [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: "628ccb2e25cf47bcaa694fb9a8d314fb" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 36561 } }
I20260812 06:18:30.740916 26821 raft_consensus.cc:399] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:30.740940 26821 raft_consensus.cc:493] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:30.740983 26821 raft_consensus.cc:3060] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:30.741815 26821 raft_consensus.cc:515] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "628ccb2e25cf47bcaa694fb9a8d314fb" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 36561 } }
I20260812 06:18:30.741989 26821 leader_election.cc:304] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [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: 628ccb2e25cf47bcaa694fb9a8d314fb; no voters: 
I20260812 06:18:30.742192 26821 leader_election.cc:290] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:30.742312 26823 raft_consensus.cc:2804] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:30.742535 26821 ts_tablet_manager.cc:1434] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Time spent starting tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:18:30.742561 26823 raft_consensus.cc:697] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 1 LEADER]: Becoming Leader. State: Replica: 628ccb2e25cf47bcaa694fb9a8d314fb, State: Running, Role: LEADER
I20260812 06:18:30.742594 26801 heartbeater.cc:499] Master 127.25.143.190:42235 was elected leader, sending a full tablet report...
I20260812 06:18:30.742754 26823 consensus_queue.cc:237] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [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: "628ccb2e25cf47bcaa694fb9a8d314fb" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 36561 } }
I20260812 06:18:30.743932 26563 catalog_manager.cc:5719] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb reported cstate change: term changed from 0 to 1, leader changed from <none> to 628ccb2e25cf47bcaa694fb9a8d314fb (127.25.143.129). New cstate: current_term: 1 leader_uuid: "628ccb2e25cf47bcaa694fb9a8d314fb" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "628ccb2e25cf47bcaa694fb9a8d314fb" member_type: VOTER last_known_addr { host: "127.25.143.129" port: 36561 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:30.797266 26174 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.050s	user 0.013s	sys 0.008s
I20260812 06:18:30.962706 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=23.023690
I20260812 06:18:31.118547 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.155s	user 0.121s	sys 0.032s Metrics: {"bytes_written":12553637,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":110,"dirs.run_cpu_time_us":228,"dirs.run_wall_time_us":782,"drs_written":1,"lbm_read_time_us":42,"lbm_reads_lt_1ms":4,"lbm_write_time_us":39792,"lbm_writes_lt_1ms":863,"peak_mem_usage":0,"reinsert_count":0,"rows_written":105,"spinlock_wait_cycles":896,"update_count":1530}
I20260812 06:18:31.119413 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling LogGCOp(cd8f5bfed58446a3b85725485dd00f7f): free 20743880 bytes of WAL
I20260812 06:18:31.119683 26691 log_reader.cc:385] T cd8f5bfed58446a3b85725485dd00f7f: removed 2 log segments from log reader
I20260812 06:18:31.119772 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000001 (ops 1-6)
I20260812 06:18:31.119814 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000002 (ops 7-11)
I20260812 06:18:31.125622 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: LogGCOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.006s	user 0.000s	sys 0.005s Metrics: {}
I20260812 06:18:31.125972 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f): 20513817 bytes on disk
I20260812 06:18:31.126396 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":70,"lbm_reads_lt_1ms":4}
I20260812 06:18:31.126958 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:31.149506 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.022s	user 0.007s	sys 0.005s Metrics: {"bytes_written":3856509,"delete_count":0,"lbm_write_time_us":5147,"lbm_writes_lt_1ms":97,"reinsert_count":0,"update_count":470}
I20260812 06:18:31.149966 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:31.160070 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3912,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:31.160555 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:31.325634 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.165s	user 0.116s	sys 0.043s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24815800,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":475,"lbm_read_time_us":13126,"lbm_reads_lt_1ms":569,"lbm_write_time_us":28762,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2816,"thread_start_us":310,"threads_started":5,"update_count":2500}
I20260812 06:18:31.326259 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:31.372222 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.046s	user 0.028s	sys 0.017s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":19313,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:31.372735 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:31.388015 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.015s	user 0.001s	sys 0.011s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5860,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:31.388520 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:31.535598 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.147s	user 0.124s	sys 0.021s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":258,"lbm_read_time_us":9582,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27974,"lbm_writes_lt_1ms":543,"mutex_wait_us":57,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":12928,"update_count":2500}
I20260812 06:18:31.536347 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=12.110812
I20260812 06:18:31.585724 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.049s	user 0.026s	sys 0.020s Metrics: {"bytes_written":13579241,"delete_count":0,"lbm_write_time_us":22053,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":333,"reinsert_count":0,"update_count":1655}
I20260812 06:18:31.586432 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:31.603193 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.017s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3241134,"delete_count":0,"lbm_write_time_us":3523,"lbm_writes_lt_1ms":82,"reinsert_count":0,"update_count":395}
I20260812 06:18:31.603631 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:31.613174 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.009s	user 0.000s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3608,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:31.613709 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:31.801354 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.187s	user 0.141s	sys 0.046s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24815775,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":916,"lbm_read_time_us":14593,"lbm_reads_lt_1ms":573,"lbm_write_time_us":31965,"lbm_writes_lt_1ms":543,"mutex_wait_us":27,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7552,"update_count":2500}
I20260812 06:18:31.802026 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:31.862099 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.060s	user 0.022s	sys 0.023s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":21147,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:31.862658 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:31.877627 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.015s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5708,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:31.878122 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:32.052099 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.174s	user 0.124s	sys 0.048s 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":849,"lbm_read_time_us":12139,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28429,"lbm_writes_lt_1ms":543,"mutex_wait_us":3,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:32.052764 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:32.122964 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.070s	user 0.033s	sys 0.033s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":26690,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:32.123817 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:32.141747 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.018s	user 0.012s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6881,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:32.142342 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:32.338712 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.196s	user 0.122s	sys 0.062s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":692,"lbm_read_time_us":12591,"lbm_reads_lt_1ms":568,"lbm_write_time_us":33298,"lbm_writes_lt_1ms":543,"mutex_wait_us":44,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7168,"update_count":2500}
I20260812 06:18:32.339298 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=18.063937
I20260812 06:18:32.401775 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.062s	user 0.030s	sys 0.030s Metrics: {"bytes_written":20512319,"delete_count":0,"lbm_write_time_us":25111,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:32.402526 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:32.416556 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.014s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102660,"delete_count":0,"lbm_write_time_us":5125,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:32.417138 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:32.452672 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.035s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1316413,"cfile_init":1,"dirs.queue_time_us":38,"dirs.run_cpu_time_us":213,"dirs.run_wall_time_us":1241,"drs_written":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2052,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:18:32.453413 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling LogGCOp(cd8f5bfed58446a3b85725485dd00f7f): free 133024369 bytes of WAL
I20260812 06:18:32.453692 26691 log_reader.cc:385] T cd8f5bfed58446a3b85725485dd00f7f: removed 13 log segments from log reader
I20260812 06:18:32.453756 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000003 (ops 12-16)
I20260812 06:18:32.453794 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000004 (ops 17-21)
I20260812 06:18:32.453830 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000005 (ops 22-26)
I20260812 06:18:32.453857 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000006 (ops 27-31)
I20260812 06:18:32.453888 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000007 (ops 32-36)
I20260812 06:18:32.453917 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000008 (ops 37-41)
I20260812 06:18:32.453946 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000009 (ops 42-46)
I20260812 06:18:32.453979 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000010 (ops 47-51)
I20260812 06:18:32.454015 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000011 (ops 52-56)
I20260812 06:18:32.454043 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000012 (ops 57-60)
I20260812 06:18:32.454072 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000013 (ops 61-65)
I20260812 06:18:32.454101 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000014 (ops 66-70)
I20260812 06:18:32.454130 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000015 (ops 71-75)
I20260812 06:18:32.485976 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: LogGCOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.032s	user 0.000s	sys 0.030s Metrics: {}
I20260812 06:18:32.486407 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=3.181125
I20260812 06:18:32.500953 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.014s	user 0.008s	sys 0.003s Metrics: {"bytes_written":4430855,"delete_count":0,"lbm_write_time_us":4427,"lbm_writes_lt_1ms":111,"reinsert_count":0,"update_count":540}
I20260812 06:18:32.501385 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:32.514315 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.013s	user 0.011s	sys 0.000s Metrics: {"bytes_written":3774459,"delete_count":0,"lbm_write_time_us":5085,"lbm_writes_lt_1ms":95,"reinsert_count":0,"update_count":460}
I20260812 06:18:32.514761 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:32.757216 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.242s	user 0.149s	sys 0.091s Metrics: {"cfile_cache_miss":834,"cfile_cache_miss_bytes":37123160,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1371,"lbm_read_time_us":16199,"lbm_reads_lt_1ms":874,"lbm_write_time_us":43534,"lbm_writes_lt_1ms":843,"mutex_wait_us":1111,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":4864,"thread_start_us":85,"threads_started":1,"update_count":4000}
I20260812 06:18:32.757925 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f): 493 bytes on disk
I20260812 06:18:32.758391 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":97,"lbm_reads_lt_1ms":4}
I20260812 06:18:32.759044 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=18.063937
I20260812 06:18:32.813742 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.055s	user 0.042s	sys 0.008s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":22864,"lbm_writes_lt_1ms":503,"mutex_wait_us":24,"reinsert_count":0,"update_count":2500}
I20260812 06:18:32.814194 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=3.181125
I20260812 06:18:32.830520 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.016s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":4999,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:32.830996 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:32.840749 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3858,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:32.841177 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:33.031294 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.190s	user 0.162s	sys 0.028s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":33020619,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":193,"lbm_read_time_us":15207,"lbm_reads_lt_1ms":773,"lbm_write_time_us":38338,"lbm_writes_lt_1ms":743,"mutex_wait_us":21,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":23168,"update_count":3500}
I20260812 06:18:33.032045 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:33.089184 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.057s	user 0.027s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21865,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:33.089877 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:33.102738 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.013s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4412,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:33.103195 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:33.278999 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.176s	user 0.126s	sys 0.039s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24815683,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":462,"lbm_read_time_us":10252,"lbm_reads_lt_1ms":564,"lbm_write_time_us":34024,"lbm_writes_lt_1ms":543,"mutex_wait_us":79,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3328,"update_count":2500}
I20260812 06:18:33.279690 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:33.343219 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.063s	user 0.037s	sys 0.008s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21388,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:33.343755 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:33.359267 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.015s	user 0.013s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5692,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:33.359895 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:33.534937 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.175s	user 0.118s	sys 0.057s 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":897,"lbm_read_time_us":12939,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28950,"lbm_writes_lt_1ms":543,"mutex_wait_us":25,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6784,"update_count":2500}
I20260812 06:18:33.535704 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:33.584353 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.048s	user 0.029s	sys 0.019s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":22077,"lbm_writes_lt_1ms":403,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":2000}
I20260812 06:18:33.584849 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:33.598176 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.013s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5016,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:33.598699 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:33.766172 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.167s	user 0.113s	sys 0.054s 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":266,"lbm_read_time_us":12210,"lbm_reads_lt_1ms":564,"lbm_write_time_us":26909,"lbm_writes_lt_1ms":543,"mutex_wait_us":63,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2560,"update_count":2500}
I20260812 06:18:33.766822 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:33.826923 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.060s	user 0.038s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21762,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:33.827428 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:33.837844 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4065,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:33.838253 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:33.877574 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.039s	user 0.028s	sys 0.000s Metrics: {"bytes_written":1152513,"cfile_init":1,"dirs.queue_time_us":61,"dirs.run_cpu_time_us":176,"dirs.run_wall_time_us":980,"drs_written":1,"lbm_read_time_us":60,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1621,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:18:33.878257 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling LogGCOp(cd8f5bfed58446a3b85725485dd00f7f): free 120553356 bytes of WAL
I20260812 06:18:33.878515 26691 log_reader.cc:385] T cd8f5bfed58446a3b85725485dd00f7f: removed 12 log segments from log reader
I20260812 06:18:33.878584 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000016 (ops 76-80)
I20260812 06:18:33.878634 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000017 (ops 81-85)
I20260812 06:18:33.878693 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000018 (ops 86-90)
I20260812 06:18:33.878737 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000019 (ops 91-95)
I20260812 06:18:33.878778 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000020 (ops 96-100)
I20260812 06:18:33.878818 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000021 (ops 101-104)
I20260812 06:18:33.878890 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000022 (ops 105-109)
I20260812 06:18:33.878929 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000023 (ops 110-114)
I20260812 06:18:33.878988 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000024 (ops 115-119)
I20260812 06:18:33.879026 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000025 (ops 120-124)
I20260812 06:18:33.879066 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000026 (ops 125-128)
I20260812 06:18:33.879106 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000027 (ops 129-133)
I20260812 06:18:33.904528 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: LogGCOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.026s	user 0.000s	sys 0.026s Metrics: {}
I20260812 06:18:33.904986 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f): 447 bytes on disk
I20260812 06:18:33.905575 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f) 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:33.906386 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=3.181125
I20260812 06:18:33.920603 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.014s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4307783,"delete_count":0,"lbm_write_time_us":4274,"lbm_writes_lt_1ms":108,"reinsert_count":0,"update_count":525}
I20260812 06:18:33.921011 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:33.930956 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.010s	user 0.001s	sys 0.008s Metrics: {"bytes_written":3897533,"delete_count":0,"lbm_write_time_us":3823,"lbm_writes_lt_1ms":98,"reinsert_count":0,"update_count":475}
I20260812 06:18:33.931445 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:34.160533 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.229s	user 0.144s	sys 0.084s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020742,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":628,"lbm_read_time_us":14972,"lbm_reads_lt_1ms":774,"lbm_write_time_us":41392,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":896,"thread_start_us":89,"threads_started":1,"update_count":3500}
I20260812 06:18:34.161128 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=18.063937
I20260812 06:18:34.223946 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.063s	user 0.038s	sys 0.021s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":29381,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":501,"reinsert_count":0,"update_count":2500}
I20260812 06:18:34.224820 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:34.251821 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.027s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5698,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:34.252323 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:34.263028 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4109,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:34.263468 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:34.457394 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.194s	user 0.133s	sys 0.047s 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":586,"lbm_read_time_us":12540,"lbm_reads_lt_1ms":773,"lbm_write_time_us":37815,"lbm_writes_lt_1ms":743,"mutex_wait_us":39,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":3500}
I20260812 06:18:34.458252 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=18.063937
I20260812 06:18:34.520721 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.062s	user 0.046s	sys 0.016s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":26992,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:18:34.521435 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:34.537230 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.016s	user 0.002s	sys 0.012s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6086,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:34.537693 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:34.708274 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.170s	user 0.132s	sys 0.038s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28918098,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":277,"lbm_read_time_us":11095,"lbm_reads_lt_1ms":664,"lbm_write_time_us":36182,"lbm_writes_lt_1ms":643,"mutex_wait_us":26,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":14208,"update_count":3000}
I20260812 06:18:34.710510 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:34.768877 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.058s	user 0.033s	sys 0.024s Metrics: {"bytes_written":16409898,"delete_count":0,"lbm_write_time_us":23208,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:34.769436 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=3.181125
I20260812 06:18:34.793926 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.024s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4430855,"delete_count":0,"lbm_write_time_us":5015,"lbm_writes_lt_1ms":111,"reinsert_count":0,"update_count":540}
I20260812 06:18:34.794477 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:34.808075 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.013s	user 0.004s	sys 0.007s Metrics: {"bytes_written":3774459,"delete_count":0,"lbm_write_time_us":5241,"lbm_writes_lt_1ms":95,"reinsert_count":0,"update_count":460}
I20260812 06:18:34.808573 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:34.979831 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.171s	user 0.138s	sys 0.032s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28918207,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":724,"lbm_read_time_us":11657,"lbm_reads_lt_1ms":673,"lbm_write_time_us":34490,"lbm_writes_lt_1ms":643,"mutex_wait_us":24,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3584,"update_count":3000}
I20260812 06:18:34.980675 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:35.026146 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.045s	user 0.023s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19845,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:35.026757 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:35.043320 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.016s	user 0.000s	sys 0.014s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6604,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:35.043854 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:35.213830 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.170s	user 0.122s	sys 0.039s 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":161,"lbm_read_time_us":12249,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30440,"lbm_writes_lt_1ms":543,"mutex_wait_us":22,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":28160,"update_count":2500}
I20260812 06:18:35.214640 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=14.095187
I20260812 06:18:35.264247 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.049s	user 0.031s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":20593,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:35.264726 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:35.305734 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushMRSOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.041s	user 0.030s	sys 0.001s Metrics: {"bytes_written":1275447,"cfile_init":1,"dirs.queue_time_us":83,"dirs.run_cpu_time_us":280,"dirs.run_wall_time_us":1228,"drs_written":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2457,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:18:35.306684 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f): 482 bytes on disk
I20260812 06:18:35.307075 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: UndoDeltaBlockGCOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4}
I20260812 06:18:35.307592 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=3.181125
I20260812 06:18:35.321105 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.013s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":4298,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:35.321568 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling LogGCOp(cd8f5bfed58446a3b85725485dd00f7f): free 124710576 bytes of WAL
I20260812 06:18:35.321805 26691 log_reader.cc:385] T cd8f5bfed58446a3b85725485dd00f7f: removed 12 log segments from log reader
I20260812 06:18:35.321848 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000028 (ops 134-138)
I20260812 06:18:35.321877 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000029 (ops 139-143)
I20260812 06:18:35.321942 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000030 (ops 144-148)
I20260812 06:18:35.321985 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000031 (ops 149-153)
I20260812 06:18:35.322041 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000032 (ops 154-158)
I20260812 06:18:35.322082 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000033 (ops 159-163)
I20260812 06:18:35.322155 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000034 (ops 164-168)
I20260812 06:18:35.322199 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000035 (ops 169-173)
I20260812 06:18:35.322218 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000036 (ops 174-178)
I20260812 06:18:35.322273 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000037 (ops 179-183)
I20260812 06:18:35.322312 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000038 (ops 184-188)
I20260812 06:18:35.322350 26691 log.cc:1079] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: Deleting log segment in path: /tmp/dist-test-task99yRv2/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515505154278-26174-0/minicluster-data/ts-0-root/wals/cd8f5bfed58446a3b85725485dd00f7f/wal-000000039 (ops 189-193)
I20260812 06:18:35.347805 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: LogGCOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.026s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:18:35.348376 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:35.364145 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.016s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3805,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:35.364645 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=2.188937
I20260812 06:18:35.374894 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: FlushDeltaMemStoresOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.010s	user 0.005s	sys 0.004s 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:35.375372 26803 maintenance_manager.cc:419] P 628ccb2e25cf47bcaa694fb9a8d314fb: Scheduling MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f): perf score=1.000000
I20260812 06:18:35.450555 26174 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.653s	user 1.785s	sys 0.146s
I20260812 06:18:35.543686 26174 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.093s	user 0.000s	sys 0.001s
I20260812 06:18:35.544297 26174 tablet_server.cc:179] TabletServer@127.25.143.129:0 shutting down...
I20260812 06:18:35.582440 26691 maintenance_manager.cc:643] P 628ccb2e25cf47bcaa694fb9a8d314fb: MajorDeltaCompactionOp(cd8f5bfed58446a3b85725485dd00f7f) complete. Timing: real 0.207s	user 0.152s	sys 0.052s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":33020735,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":729,"lbm_read_time_us":14035,"lbm_reads_lt_1ms":770,"lbm_write_time_us":32481,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":2304,"thread_start_us":96,"threads_started":1,"update_count":3500}
I20260812 06:18:35.583058 26174 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:35.583380 26174 tablet_replica.cc:333] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb: stopping tablet replica
I20260812 06:18:35.583514 26174 raft_consensus.cc:2243] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:35.583654 26174 raft_consensus.cc:2272] T cd8f5bfed58446a3b85725485dd00f7f P 628ccb2e25cf47bcaa694fb9a8d314fb [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:35.589497 26174 tablet_server.cc:196] TabletServer@127.25.143.129:0 shutdown complete.
I20260812 06:18:35.637730 26174 master.cc:562] Master@127.25.143.190:42235 shutting down...
I20260812 06:18:35.641016 26174 raft_consensus.cc:2243] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:35.641186 26174 raft_consensus.cc:2272] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:35.641235 26174 tablet_replica.cc:333] T 00000000000000000000000000000000 P f5abb1c40f0d481b9354cc471e663214: stopping tablet replica
I20260812 06:18:35.653596 26174 master.cc:584] Master@127.25.143.190:42235 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5140 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10575 ms total)

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