[==========] 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:56.210073 19989 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.19.133.126:33321
I20260812 06:18:56.211143 19989 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:56.211778 19989 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:56.218716 19998 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:56.218720 20004 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:56.218763 19989 server_base.cc:1061] running on GCE node
W20260812 06:18:56.219079 20001 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:56.219614 19989 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:56.219710 19989 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:56.219741 19989 hybrid_clock.cc:648] HybridClock initialized: now 1786515536219740 us; error 0 us; skew 500 ppm
I20260812 06:18:56.221673 19989 webserver.cc:533] Webserver started at http://127.19.133.126:43323/ using document root <none> and password file <none>
I20260812 06:18:56.222198 19989 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:56.222259 19989 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:56.222466 19989 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:56.224110 19989 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/master-0-root/instance:
uuid: "d7f6f7f118284d98842eb5fe196704c4"
format_stamp: "Formatted at 2026-08-12 06:18:56 on dist-test-slave-04bb"
I20260812 06:18:56.227787 19989 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
I20260812 06:18:56.229988 20012 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:56.231066 19989 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
I20260812 06:18:56.231213 19989 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/master-0-root
uuid: "d7f6f7f118284d98842eb5fe196704c4"
format_stamp: "Formatted at 2026-08-12 06:18:56 on dist-test-slave-04bb"
I20260812 06:18:56.231336 19989 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-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:56.254737 19989 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:56.255460 19989 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:56.255658 19989 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:56.263796 19989 rpc_server.cc:307] RPC server started. Bound to: 127.19.133.126:33321
I20260812 06:18:56.263810 20098 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.19.133.126:33321 every 8 connection(s)
I20260812 06:18:56.266054 20099 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:56.271664 20099 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4: Bootstrap starting.
I20260812 06:18:56.274026 20099 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:56.274968 20099 log.cc:826] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4: Log is configured to *not* fsync() on all Append() calls
I20260812 06:18:56.276769 20099 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4: No bootstrap required, opened a new log
I20260812 06:18:56.279538 20099 raft_consensus.cc:359] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d7f6f7f118284d98842eb5fe196704c4" member_type: VOTER }
I20260812 06:18:56.279697 20099 raft_consensus.cc:385] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:56.279757 20099 raft_consensus.cc:740] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d7f6f7f118284d98842eb5fe196704c4, State: Initialized, Role: FOLLOWER
I20260812 06:18:56.280352 20099 consensus_queue.cc:260] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [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: "d7f6f7f118284d98842eb5fe196704c4" member_type: VOTER }
I20260812 06:18:56.280524 20099 raft_consensus.cc:399] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:56.280627 20099 raft_consensus.cc:493] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:56.280817 20099 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:56.281627 20099 raft_consensus.cc:515] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d7f6f7f118284d98842eb5fe196704c4" member_type: VOTER }
I20260812 06:18:56.282078 20099 leader_election.cc:304] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [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: d7f6f7f118284d98842eb5fe196704c4; no voters: 
I20260812 06:18:56.282414 20099 leader_election.cc:290] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:56.282564 20103 raft_consensus.cc:2804] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:56.282833 20103 raft_consensus.cc:697] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 1 LEADER]: Becoming Leader. State: Replica: d7f6f7f118284d98842eb5fe196704c4, State: Running, Role: LEADER
I20260812 06:18:56.283270 20103 consensus_queue.cc:237] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [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: "d7f6f7f118284d98842eb5fe196704c4" member_type: VOTER }
I20260812 06:18:56.283501 20099 sys_catalog.cc:565] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:56.285202 20105 sys_catalog.cc:455] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [sys.catalog]: SysCatalogTable state changed. Reason: New leader d7f6f7f118284d98842eb5fe196704c4. Latest consensus state: current_term: 1 leader_uuid: "d7f6f7f118284d98842eb5fe196704c4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d7f6f7f118284d98842eb5fe196704c4" member_type: VOTER } }
I20260812 06:18:56.285259 20104 sys_catalog.cc:455] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d7f6f7f118284d98842eb5fe196704c4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d7f6f7f118284d98842eb5fe196704c4" member_type: VOTER } }
I20260812 06:18:56.285346 20105 sys_catalog.cc:458] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:56.285383 20104 sys_catalog.cc:458] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:56.285737 20120 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:56.285949 19989 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:18:56.288281 20120 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:56.292856 20120 catalog_manager.cc:1383] Generated new cluster ID: ec66be0c09af48039a274dd7c3af8f7c
I20260812 06:18:56.292932 20120 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:56.310016 20120 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:56.310915 20120 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:56.324807 20120 catalog_manager.cc:6092] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4: Generated new TSK 0
I20260812 06:18:56.325517 20120 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:56.351173 19989 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:56.354422 20132 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:56.354511 19989 server_base.cc:1061] running on GCE node
W20260812 06:18:56.354403 20136 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:56.354617 20130 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:56.354835 19989 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:56.354888 19989 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:56.354911 19989 hybrid_clock.cc:648] HybridClock initialized: now 1786515536354911 us; error 0 us; skew 500 ppm
I20260812 06:18:56.355854 19989 webserver.cc:533] Webserver started at http://127.19.133.65:39279/ using document root <none> and password file <none>
I20260812 06:18:56.356021 19989 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:56.356078 19989 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:56.356145 19989 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:56.356621 19989 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/instance:
uuid: "e82e4e20cf6b4295a38ccad47023b452"
format_stamp: "Formatted at 2026-08-12 06:18:56 on dist-test-slave-04bb"
I20260812 06:18:56.358610 19989 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:18:56.359755 20145 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:56.360050 19989 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:18:56.360127 19989 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root
uuid: "e82e4e20cf6b4295a38ccad47023b452"
format_stamp: "Formatted at 2026-08-12 06:18:56 on dist-test-slave-04bb"
I20260812 06:18:56.360234 19989 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-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:56.371037 19989 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:56.371523 19989 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:56.372056 19989 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:56.373082 19989 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:56.373136 19989 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:56.373215 19989 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:56.373258 19989 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:56.381008 19989 rpc_server.cc:307] RPC server started. Bound to: 127.19.133.65:42511
I20260812 06:18:56.381045 20253 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.19.133.65:42511 every 8 connection(s)
I20260812 06:18:56.391551 20255 heartbeater.cc:344] Connected to a master server at 127.19.133.126:33321
I20260812 06:18:56.391824 20255 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:56.392288 20255 heartbeater.cc:507] Master 127.19.133.126:33321 requested a full tablet report, sending...
I20260812 06:18:56.393745 20044 ts_manager.cc:194] Registered new tserver with Master: e82e4e20cf6b4295a38ccad47023b452 (127.19.133.65:42511)
I20260812 06:18:56.394605 19989 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.012888986s
I20260812 06:18:56.394912 20044 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:45302
I20260812 06:18:56.404735 20044 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:45316:
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:56.419165 20194 tablet_service.cc:1511] Processing CreateTablet for tablet face0084fb6640168db8cdf5859abc7e (DEFAULT_TABLE table=heavy-update-compaction-test [id=073e58e699734a97a92ac0d922b60786]), partition=
I20260812 06:18:56.419718 20194 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet face0084fb6640168db8cdf5859abc7e. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:56.422699 20269 tablet_bootstrap.cc:492] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Bootstrap starting.
I20260812 06:18:56.423947 20269 tablet_bootstrap.cc:654] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:56.425123 20269 tablet_bootstrap.cc:492] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: No bootstrap required, opened a new log
I20260812 06:18:56.425249 20269 ts_tablet_manager.cc:1403] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:18:56.425691 20269 raft_consensus.cc:359] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "e82e4e20cf6b4295a38ccad47023b452" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 42511 } }
I20260812 06:18:56.425817 20269 raft_consensus.cc:385] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:56.425881 20269 raft_consensus.cc:740] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: e82e4e20cf6b4295a38ccad47023b452, State: Initialized, Role: FOLLOWER
I20260812 06:18:56.426118 20269 consensus_queue.cc:260] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [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: "e82e4e20cf6b4295a38ccad47023b452" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 42511 } }
I20260812 06:18:56.426311 20269 raft_consensus.cc:399] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:56.426386 20269 raft_consensus.cc:493] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:56.426431 20269 raft_consensus.cc:3060] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:56.427270 20269 raft_consensus.cc:515] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "e82e4e20cf6b4295a38ccad47023b452" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 42511 } }
I20260812 06:18:56.427436 20269 leader_election.cc:304] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [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: e82e4e20cf6b4295a38ccad47023b452; no voters: 
I20260812 06:18:56.427680 20269 leader_election.cc:290] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:56.427793 20271 raft_consensus.cc:2804] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:56.428078 20271 raft_consensus.cc:697] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 1 LEADER]: Becoming Leader. State: Replica: e82e4e20cf6b4295a38ccad47023b452, State: Running, Role: LEADER
I20260812 06:18:56.428105 20269 ts_tablet_manager.cc:1434] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:18:56.428419 20255 heartbeater.cc:499] Master 127.19.133.126:33321 was elected leader, sending a full tablet report...
I20260812 06:18:56.428870 20271 consensus_queue.cc:237] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [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: "e82e4e20cf6b4295a38ccad47023b452" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 42511 } }
I20260812 06:18:56.431802 20044 catalog_manager.cc:5719] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 reported cstate change: term changed from 0 to 1, leader changed from <none> to e82e4e20cf6b4295a38ccad47023b452 (127.19.133.65). New cstate: current_term: 1 leader_uuid: "e82e4e20cf6b4295a38ccad47023b452" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "e82e4e20cf6b4295a38ccad47023b452" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 42511 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:56.497828 19989 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.058s	user 0.016s	sys 0.011s
I20260812 06:18:56.632189 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushMRSOp(face0084fb6640168db8cdf5859abc7e): perf score=16.078378
I20260812 06:18:56.825404 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushMRSOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.193s	user 0.153s	sys 0.035s Metrics: {"bytes_written":12389544,"cfile_init":1,"compiler_manager_pool.queue_time_us":438,"delete_count":0,"dirs.queue_time_us":61,"dirs.run_cpu_time_us":257,"dirs.run_wall_time_us":984,"drs_written":1,"lbm_read_time_us":75,"lbm_reads_lt_1ms":4,"lbm_write_time_us":48065,"lbm_writes_lt_1ms":759,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":293632,"thread_start_us":142,"threads_started":1,"update_count":1510}
I20260812 06:18:56.826579 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling LogGCOp(face0084fb6640168db8cdf5859abc7e): free 20743880 bytes of WAL
I20260812 06:18:56.826902 20157 log_reader.cc:385] T face0084fb6640168db8cdf5859abc7e: removed 2 log segments from log reader
I20260812 06:18:56.826975 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000001 (ops 1-6)
I20260812 06:18:56.827029 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000002 (ops 7-11)
I20260812 06:18:56.832959 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: LogGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.006s	user 0.000s	sys 0.006s Metrics: {}
I20260812 06:18:56.833328 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e): 16411393 bytes on disk
I20260812 06:18:56.834139 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":70,"lbm_reads_lt_1ms":4}
I20260812 06:18:56.834551 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=6.157687
I20260812 06:18:56.866636 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.032s	user 0.018s	sys 0.012s Metrics: {"bytes_written":7712796,"delete_count":0,"lbm_write_time_us":11488,"lbm_writes_lt_1ms":191,"reinsert_count":0,"update_count":940}
I20260812 06:18:56.867349 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:57.049232 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.182s	user 0.120s	sys 0.058s Metrics: {"cfile_cache_miss":522,"cfile_cache_miss_bytes":24364462,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":511,"lbm_read_time_us":14383,"lbm_reads_lt_1ms":550,"lbm_write_time_us":31573,"lbm_writes_lt_1ms":533,"peak_mem_usage":61665166,"reinsert_count":0,"spinlock_wait_cycles":896,"thread_start_us":325,"threads_started":5,"update_count":2450}
I20260812 06:18:57.049896 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=15.087375
I20260812 06:18:57.102279 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.052s	user 0.038s	sys 0.012s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":23541,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:18:57.102753 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:57.114349 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4386,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:57.114806 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:57.310153 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.195s	user 0.132s	sys 0.048s Metrics: {"cfile_cache_miss":542,"cfile_cache_miss_bytes":25184929,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":899,"lbm_read_time_us":10832,"lbm_reads_lt_1ms":582,"lbm_write_time_us":35909,"lbm_writes_lt_1ms":553,"mutex_wait_us":35,"peak_mem_usage":63526250,"reinsert_count":0,"spinlock_wait_cycles":8960,"update_count":2550}
I20260812 06:18:57.310804 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:18:57.368885 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.058s	user 0.026s	sys 0.031s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":26307,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:57.369354 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:57.382376 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.013s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4846,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:57.382929 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:57.535435 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.152s	user 0.116s	sys 0.034s 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":50,"lbm_read_time_us":9757,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32106,"lbm_writes_lt_1ms":543,"mutex_wait_us":46,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7808,"update_count":2500}
I20260812 06:18:57.536010 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=11.118625
I20260812 06:18:57.572721 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.037s	user 0.029s	sys 0.007s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":15996,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:57.573339 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:57.602257 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.029s	user 0.013s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5902,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:57.602721 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:57.613389 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4311,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:57.613914 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:57.764071 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.150s	user 0.107s	sys 0.032s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774801,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":831,"lbm_read_time_us":10065,"lbm_reads_lt_1ms":573,"lbm_write_time_us":29742,"lbm_writes_lt_1ms":543,"mutex_wait_us":299,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:57.764746 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:18:57.818874 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.054s	user 0.039s	sys 0.008s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":22262,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:57.819465 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:57.831619 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.012s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4220,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:57.832216 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:57.992861 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.160s	user 0.116s	sys 0.032s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":738,"lbm_read_time_us":11448,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31396,"lbm_writes_lt_1ms":543,"mutex_wait_us":307,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1664,"update_count":2500}
I20260812 06:18:57.993347 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:18:58.053318 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.060s	user 0.039s	sys 0.012s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23555,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:58.053820 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:58.065397 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4146,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.065868 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushMRSOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:58.098492 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushMRSOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.032s	user 0.030s	sys 0.000s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":69,"dirs.run_cpu_time_us":213,"dirs.run_wall_time_us":1355,"drs_written":1,"lbm_read_time_us":100,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1804,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30,"spinlock_wait_cycles":10624}
I20260812 06:18:58.099436 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling LogGCOp(face0084fb6640168db8cdf5859abc7e): free 124710316 bytes of WAL
I20260812 06:18:58.099705 20157 log_reader.cc:385] T face0084fb6640168db8cdf5859abc7e: removed 12 log segments from log reader
I20260812 06:18:58.099753 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000003 (ops 12-16)
I20260812 06:18:58.099783 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000004 (ops 17-21)
I20260812 06:18:58.099848 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000005 (ops 22-26)
I20260812 06:18:58.099888 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000006 (ops 27-31)
I20260812 06:18:58.099927 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000007 (ops 32-36)
I20260812 06:18:58.099968 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000008 (ops 37-41)
I20260812 06:18:58.100006 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000009 (ops 42-46)
I20260812 06:18:58.100045 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000010 (ops 47-51)
I20260812 06:18:58.100083 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000011 (ops 52-56)
I20260812 06:18:58.100147 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000012 (ops 57-61)
I20260812 06:18:58.100180 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000013 (ops 62-66)
I20260812 06:18:58.100217 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000014 (ops 67-71)
I20260812 06:18:58.129236 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: LogGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.030s	user 0.000s	sys 0.029s Metrics: {"spinlock_wait_cycles":1280}
I20260812 06:18:58.129647 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=6.157687
I20260812 06:18:58.155756 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.026s	user 0.005s	sys 0.015s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":9392,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:58.156323 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:58.364261 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.208s	user 0.152s	sys 0.052s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979631,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":426,"lbm_read_time_us":14012,"lbm_reads_lt_1ms":765,"lbm_write_time_us":46623,"lbm_writes_lt_1ms":743,"mutex_wait_us":55,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":16768,"thread_start_us":77,"threads_started":1,"update_count":3500}
I20260812 06:18:58.365141 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e): 474 bytes on disk
I20260812 06:18:58.365691 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":68,"lbm_reads_lt_1ms":4}
I20260812 06:18:58.366228 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=15.087375
I20260812 06:18:58.419198 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.053s	user 0.039s	sys 0.008s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":22676,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:18:58.419664 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:58.436045 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.016s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6615,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.436479 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:58.454979 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.018s	user 0.012s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":9722,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":92,"reinsert_count":0,"update_count":450}
I20260812 06:18:58.455538 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:58.628916 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.173s	user 0.124s	sys 0.048s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877206,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":369,"lbm_read_time_us":12637,"lbm_reads_lt_1ms":665,"lbm_write_time_us":37363,"lbm_writes_lt_1ms":643,"mutex_wait_us":29,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":69760,"update_count":3000}
I20260812 06:18:58.629446 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:18:58.672796 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.043s	user 0.027s	sys 0.016s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":19269,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:58.673321 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:58.688706 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.015s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5927,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.689383 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:58.845060 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.155s	user 0.116s	sys 0.035s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":295,"lbm_read_time_us":10516,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30716,"lbm_writes_lt_1ms":543,"mutex_wait_us":94,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:58.845768 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=12.110812
I20260812 06:18:58.882573 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.037s	user 0.018s	sys 0.016s Metrics: {"bytes_written":13661282,"delete_count":0,"lbm_write_time_us":16283,"lbm_writes_lt_1ms":336,"reinsert_count":0,"update_count":1665}
I20260812 06:18:58.883044 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=1.196750
I20260812 06:18:58.898100 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.015s	user 0.006s	sys 0.003s Metrics: {"bytes_written":2748830,"delete_count":0,"lbm_write_time_us":4170,"lbm_writes_lt_1ms":70,"reinsert_count":0,"update_count":335}
I20260812 06:18:58.898618 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:59.047003 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.148s	user 0.103s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672240,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":370,"lbm_read_time_us":9873,"lbm_reads_lt_1ms":464,"lbm_write_time_us":25354,"lbm_writes_lt_1ms":443,"mutex_wait_us":46,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":7040,"update_count":2000}
I20260812 06:18:59.047472 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:18:59.098522 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.051s	user 0.025s	sys 0.019s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":20682,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:59.098971 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:59.117786 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.019s	user 0.002s	sys 0.015s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4264,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:59.118299 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:59.294826 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.176s	user 0.126s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":403,"lbm_read_time_us":13054,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29579,"lbm_writes_lt_1ms":543,"mutex_wait_us":65,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:59.295315 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:18:59.350284 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.055s	user 0.037s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":25074,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:18:59.350883 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:59.363289 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.012s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4767,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:59.363739 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:59.554486 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.191s	user 0.102s	sys 0.070s 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":319,"lbm_read_time_us":11468,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31208,"lbm_writes_lt_1ms":543,"mutex_wait_us":67,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":8704,"update_count":2500}
I20260812 06:18:59.555265 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:18:59.611641 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.056s	user 0.029s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":27380,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:18:59.612123 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:18:59.624476 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.012s	user 0.003s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5038,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:59.624944 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushMRSOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:59.654747 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushMRSOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.030s	user 0.028s	sys 0.000s Metrics: {"bytes_written":1316419,"cfile_init":1,"dirs.queue_time_us":87,"dirs.run_cpu_time_us":196,"dirs.run_wall_time_us":1283,"drs_written":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2008,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:18:59.655507 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling LogGCOp(face0084fb6640168db8cdf5859abc7e): free 132571345 bytes of WAL
I20260812 06:18:59.655761 20157 log_reader.cc:385] T face0084fb6640168db8cdf5859abc7e: removed 13 log segments from log reader
I20260812 06:18:59.655835 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000015 (ops 72-76)
I20260812 06:18:59.655887 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000016 (ops 77-80)
I20260812 06:18:59.655926 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000017 (ops 81-85)
I20260812 06:18:59.655967 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000018 (ops 86-90)
I20260812 06:18:59.656005 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000019 (ops 91-95)
I20260812 06:18:59.656042 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000020 (ops 96-100)
I20260812 06:18:59.656080 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000021 (ops 101-105)
I20260812 06:18:59.656119 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000022 (ops 106-110)
I20260812 06:18:59.656157 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000023 (ops 111-115)
I20260812 06:18:59.656194 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000024 (ops 116-120)
I20260812 06:18:59.656243 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000025 (ops 121-125)
I20260812 06:18:59.656284 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000026 (ops 126-130)
I20260812 06:18:59.656324 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000027 (ops 131-134)
I20260812 06:18:59.690076 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: LogGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.034s	user 0.000s	sys 0.032s Metrics: {}
I20260812 06:18:59.690699 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e): 492 bytes on disk
I20260812 06:18:59.691298 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":74,"lbm_reads_lt_1ms":4}
I20260812 06:18:59.691872 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=6.157687
I20260812 06:18:59.716755 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.025s	user 0.009s	sys 0.015s Metrics: {"bytes_written":7466644,"delete_count":0,"lbm_write_time_us":10853,"lbm_writes_lt_1ms":185,"reinsert_count":0,"update_count":910}
I20260812 06:18:59.717259 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:18:59.925118 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.208s	user 0.132s	sys 0.076s Metrics: {"cfile_cache_miss":715,"cfile_cache_miss_bytes":32241197,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":172,"lbm_read_time_us":14900,"lbm_reads_lt_1ms":751,"lbm_write_time_us":38476,"lbm_writes_lt_1ms":725,"peak_mem_usage":85149646,"reinsert_count":0,"spinlock_wait_cycles":2176,"thread_start_us":81,"threads_started":1,"update_count":3410}
I20260812 06:18:59.925796 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=15.087375
I20260812 06:18:59.984490 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.057s	user 0.020s	sys 0.032s Metrics: {"bytes_written":17558582,"delete_count":0,"lbm_write_time_us":26366,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":429,"reinsert_count":0,"update_count":2140}
I20260812 06:18:59.985062 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:00.010780 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.025s	user 0.013s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5818,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:00.011283 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:00.021214 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3658,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:00.021680 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:19:00.235579 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.214s	user 0.151s	sys 0.059s Metrics: {"cfile_cache_miss":651,"cfile_cache_miss_bytes":29615646,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":2500,"lbm_read_time_us":15219,"lbm_reads_lt_1ms":691,"lbm_write_time_us":36845,"lbm_writes_lt_1ms":661,"mutex_wait_us":582,"peak_mem_usage":77321486,"reinsert_count":0,"spinlock_wait_cycles":1536,"update_count":3090}
I20260812 06:19:00.236353 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=16.079562
I20260812 06:19:00.292596 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.056s	user 0.036s	sys 0.020s Metrics: {"bytes_written":17886768,"delete_count":0,"lbm_write_time_us":25053,"lbm_writes_lt_1ms":439,"reinsert_count":0,"update_count":2180}
I20260812 06:19:00.293190 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=1.196750
I20260812 06:19:00.308188 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.015s	user 0.007s	sys 0.000s Metrics: {"bytes_written":3036009,"delete_count":0,"lbm_write_time_us":3444,"lbm_writes_lt_1ms":77,"reinsert_count":0,"update_count":370}
I20260812 06:19:00.308784 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:00.318773 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.010s	user 0.000s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3792,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:00.319283 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:19:00.523870 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.204s	user 0.140s	sys 0.064s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877182,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":129,"lbm_read_time_us":15789,"lbm_reads_lt_1ms":673,"lbm_write_time_us":33958,"lbm_writes_lt_1ms":643,"mutex_wait_us":35,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":83072,"update_count":3000}
I20260812 06:19:00.524679 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:19:00.577163 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.052s	user 0.031s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23688,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:00.577847 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:00.591315 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5425,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:00.591929 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:19:00.763833 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.172s	user 0.136s	sys 0.036s 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":567,"lbm_read_time_us":13254,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29466,"lbm_writes_lt_1ms":543,"mutex_wait_us":22,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1920,"update_count":2500}
I20260812 06:19:00.764339 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:19:00.826633 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.062s	user 0.025s	sys 0.035s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":22682,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:00.827327 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:00.845193 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.018s	user 0.005s	sys 0.012s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6823,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:00.845839 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:19:01.008394 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.162s	user 0.122s	sys 0.040s 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":938,"lbm_read_time_us":12648,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28851,"lbm_writes_lt_1ms":543,"mutex_wait_us":223,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13824,"update_count":2500}
I20260812 06:19:01.009090 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=11.118625
I20260812 06:19:01.038671 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.029s	user 0.018s	sys 0.009s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":13218,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:19:01.039177 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:01.055863 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.016s	user 0.009s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4287,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:01.059734 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushMRSOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:19:01.093729 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushMRSOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.034s	user 0.026s	sys 0.005s Metrics: {"bytes_written":1193507,"cfile_init":1,"dirs.queue_time_us":56,"dirs.run_cpu_time_us":256,"dirs.run_wall_time_us":1238,"drs_written":1,"lbm_read_time_us":105,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2145,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:19:01.094398 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling LogGCOp(face0084fb6640168db8cdf5859abc7e): free 108988758 bytes of WAL
I20260812 06:19:01.094625 20157 log_reader.cc:385] T face0084fb6640168db8cdf5859abc7e: removed 11 log segments from log reader
I20260812 06:19:01.094672 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000028 (ops 135-139)
I20260812 06:19:01.094702 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000029 (ops 140-144)
I20260812 06:19:01.094774 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000030 (ops 145-149)
I20260812 06:19:01.094817 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000031 (ops 150-154)
I20260812 06:19:01.094857 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000032 (ops 155-159)
I20260812 06:19:01.094899 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000033 (ops 160-164)
I20260812 06:19:01.094941 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000034 (ops 165-168)
I20260812 06:19:01.095001 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000035 (ops 169-173)
I20260812 06:19:01.095037 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000036 (ops 174-178)
I20260812 06:19:01.095077 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000037 (ops 179-183)
I20260812 06:19:01.095117 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000038 (ops 184-188)
I20260812 06:19:01.117478 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: LogGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.023s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:19:01.117920 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:01.141505 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.023s	user 0.005s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4455,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:01.142001 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling LogGCOp(face0084fb6640168db8cdf5859abc7e): free 12017949 bytes of WAL
I20260812 06:19:01.142231 20157 log_reader.cc:385] T face0084fb6640168db8cdf5859abc7e: removed 1 log segments from log reader
I20260812 06:19:01.142284 20157 log.cc:1079] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/face0084fb6640168db8cdf5859abc7e/wal-000000039 (ops 189-193)
I20260812 06:19:01.144743 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: LogGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.003s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:19:01.145081 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=2.188937
I20260812 06:19:01.158113 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.013s	user 0.001s	sys 0.011s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5240,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:01.158720 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e): 462 bytes on disk
I20260812 06:19:01.159328 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: UndoDeltaBlockGCOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":101,"lbm_reads_lt_1ms":4}
I20260812 06:19:01.160120 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:19:01.318771 19989 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.821s	user 1.791s	sys 0.119s
I20260812 06:19:01.356738 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.196s	user 0.130s	sys 0.067s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877330,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"lbm_read_time_us":12475,"lbm_reads_lt_1ms":670,"lbm_write_time_us":32948,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":3000}
I20260812 06:19:01.357359 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e): perf score=14.095187
I20260812 06:19:01.403833 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: FlushDeltaMemStoresOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.046s	user 0.025s	sys 0.020s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":21707,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:01.404351 20256 maintenance_manager.cc:419] P e82e4e20cf6b4295a38ccad47023b452: Scheduling MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e): perf score=1.000000
I20260812 06:19:01.447005 19989 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.128s	user 0.000s	sys 0.001s
I20260812 06:19:01.447607 19989 tablet_server.cc:179] TabletServer@127.19.133.65:0 shutting down...
I20260812 06:19:01.530151 20157 maintenance_manager.cc:643] P e82e4e20cf6b4295a38ccad47023b452: MajorDeltaCompactionOp(face0084fb6640168db8cdf5859abc7e) complete. Timing: real 0.126s	user 0.095s	sys 0.030s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672158,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":788,"lbm_read_time_us":9747,"lbm_reads_lt_1ms":467,"lbm_write_time_us":28002,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":441,"mutex_wait_us":295,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":4480,"update_count":2000}
I20260812 06:19:01.530975 19989 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:19:01.531400 19989 tablet_replica.cc:333] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452: stopping tablet replica
I20260812 06:19:01.531643 19989 raft_consensus.cc:2243] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:01.531876 19989 raft_consensus.cc:2272] T face0084fb6640168db8cdf5859abc7e P e82e4e20cf6b4295a38ccad47023b452 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:01.547164 19989 tablet_server.cc:196] TabletServer@127.19.133.65:0 shutdown complete.
I20260812 06:19:01.571494 19989 master.cc:562] Master@127.19.133.126:33321 shutting down...
I20260812 06:19:01.575395 19989 raft_consensus.cc:2243] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:01.575567 19989 raft_consensus.cc:2272] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:01.575623 19989 tablet_replica.cc:333] T 00000000000000000000000000000000 P d7f6f7f118284d98842eb5fe196704c4: stopping tablet replica
I20260812 06:19:01.587996 19989 master.cc:584] Master@127.19.133.126:33321 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5469 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:19:01.678671 19989 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.19.133.126:37033
I20260812 06:19:01.679044 19989 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:01.681245 20305 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:19:01.681245 20308 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:19:01.681274 20310 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:19:01.681313 19989 server_base.cc:1061] running on GCE node
I20260812 06:19:01.681672 19989 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:01.681715 19989 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:19:01.681730 19989 hybrid_clock.cc:648] HybridClock initialized: now 1786515541681731 us; error 0 us; skew 500 ppm
I20260812 06:19:01.682559 19989 webserver.cc:533] Webserver started at http://127.19.133.126:46233/ using document root <none> and password file <none>
I20260812 06:19:01.682687 19989 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:01.682727 19989 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:01.682780 19989 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:01.683128 19989 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/master-0-root/instance:
uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f"
format_stamp: "Formatted at 2026-08-12 06:19:01 on dist-test-slave-04bb"
I20260812 06:19:01.684581 19989 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:19:01.685483 20327 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:19:01.685748 19989 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:01.685846 19989 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/master-0-root
uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f"
format_stamp: "Formatted at 2026-08-12 06:19:01 on dist-test-slave-04bb"
I20260812 06:19:01.685935 19989 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-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:19:01.704280 19989 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:01.704782 19989 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:01.708916 19989 rpc_server.cc:307] RPC server started. Bound to: 127.19.133.126:37033
I20260812 06:19:01.711380 20420 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.19.133.126:37033 every 8 connection(s)
I20260812 06:19:01.716512 20422 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:19:01.726418 20422 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f: Bootstrap starting.
I20260812 06:19:01.727269 20422 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:01.728380 20422 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f: No bootstrap required, opened a new log
I20260812 06:19:01.728866 20422 raft_consensus.cc:359] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f" member_type: VOTER }
I20260812 06:19:01.728976 20422 raft_consensus.cc:385] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:01.729027 20422 raft_consensus.cc:740] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: b4f5c61badcb4ab8b809d648a8a6bf4f, State: Initialized, Role: FOLLOWER
I20260812 06:19:01.729223 20422 consensus_queue.cc:260] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [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: "b4f5c61badcb4ab8b809d648a8a6bf4f" member_type: VOTER }
I20260812 06:19:01.729333 20422 raft_consensus.cc:399] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:01.729383 20422 raft_consensus.cc:493] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:01.729439 20422 raft_consensus.cc:3060] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:01.730119 20422 raft_consensus.cc:515] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f" member_type: VOTER }
I20260812 06:19:01.730266 20422 leader_election.cc:304] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [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: b4f5c61badcb4ab8b809d648a8a6bf4f; no voters: 
I20260812 06:19:01.730471 20422 leader_election.cc:290] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:01.730634 20425 raft_consensus.cc:2804] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:01.730860 20425 raft_consensus.cc:697] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 1 LEADER]: Becoming Leader. State: Replica: b4f5c61badcb4ab8b809d648a8a6bf4f, State: Running, Role: LEADER
I20260812 06:19:01.730995 20422 sys_catalog.cc:565] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:19:01.730996 20425 consensus_queue.cc:237] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [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: "b4f5c61badcb4ab8b809d648a8a6bf4f" member_type: VOTER }
I20260812 06:19:01.731532 20428 sys_catalog.cc:455] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [sys.catalog]: SysCatalogTable state changed. Reason: New leader b4f5c61badcb4ab8b809d648a8a6bf4f. Latest consensus state: current_term: 1 leader_uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f" member_type: VOTER } }
I20260812 06:19:01.731500 20427 sys_catalog.cc:455] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "b4f5c61badcb4ab8b809d648a8a6bf4f" member_type: VOTER } }
I20260812 06:19:01.731638 20428 sys_catalog.cc:458] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:01.731702 20427 sys_catalog.cc:458] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:01.732257 20437 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:19:01.733064 20437 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:19:01.733450 19989 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:19:01.734827 20437 catalog_manager.cc:1383] Generated new cluster ID: b0ed28dfd4da45dd9e63104fd72552a3
I20260812 06:19:01.734885 20437 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:19:01.743026 20437 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:19:01.743584 20437 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:19:01.751480 20437 catalog_manager.cc:6092] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f: Generated new TSK 0
I20260812 06:19:01.751677 20437 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:19:01.765712 19989 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:01.767632 20463 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:19:01.767763 20459 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:19:01.767596 19989 server_base.cc:1061] running on GCE node
W20260812 06:19:01.767623 20460 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:19:01.768078 19989 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:01.768121 19989 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:19:01.768136 19989 hybrid_clock.cc:648] HybridClock initialized: now 1786515541768137 us; error 0 us; skew 500 ppm
I20260812 06:19:01.769057 19989 webserver.cc:533] Webserver started at http://127.19.133.65:43319/ using document root <none> and password file <none>
I20260812 06:19:01.769248 19989 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:01.769297 19989 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:01.769397 19989 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:01.769783 19989 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/instance:
uuid: "3939befd27514925893de5164a3d838a"
format_stamp: "Formatted at 2026-08-12 06:19:01 on dist-test-slave-04bb"
I20260812 06:19:01.771195 19989 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:19:01.772063 20473 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:19:01.772305 19989 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:01.772392 19989 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root
uuid: "3939befd27514925893de5164a3d838a"
format_stamp: "Formatted at 2026-08-12 06:19:01 on dist-test-slave-04bb"
I20260812 06:19:01.772473 19989 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-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:19:01.788291 19989 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:01.788717 19989 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:01.789041 19989 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:19:01.789516 19989 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:19:01.789577 19989 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:01.789628 19989 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:19:01.789685 19989 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:01.794049 19989 rpc_server.cc:307] RPC server started. Bound to: 127.19.133.65:37555
I20260812 06:19:01.794094 20577 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.19.133.65:37555 every 8 connection(s)
I20260812 06:19:01.802664 20578 heartbeater.cc:344] Connected to a master server at 127.19.133.126:37033
I20260812 06:19:01.802783 20578 heartbeater.cc:461] Registering TS with master...
I20260812 06:19:01.802996 20578 heartbeater.cc:507] Master 127.19.133.126:37033 requested a full tablet report, sending...
I20260812 06:19:01.803671 20358 ts_manager.cc:194] Registered new tserver with Master: 3939befd27514925893de5164a3d838a (127.19.133.65:37555)
I20260812 06:19:01.804445 20358 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:60956
I20260812 06:19:01.804580 19989 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.010082708s
I20260812 06:19:01.811926 20358 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:60970:
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:19:01.820811 20522 tablet_service.cc:1511] Processing CreateTablet for tablet a13070ebb6334c03b2e47ad21f47dbe8 (DEFAULT_TABLE table=heavy-update-compaction-test [id=5c7dd8b223164fbc9a54307076e9f83a]), partition=
I20260812 06:19:01.821138 20522 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a13070ebb6334c03b2e47ad21f47dbe8. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:01.823529 20595 tablet_bootstrap.cc:492] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Bootstrap starting.
I20260812 06:19:01.824482 20595 tablet_bootstrap.cc:654] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:01.825688 20595 tablet_bootstrap.cc:492] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: No bootstrap required, opened a new log
I20260812 06:19:01.825812 20595 ts_tablet_manager.cc:1403] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:19:01.826370 20595 raft_consensus.cc:359] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "3939befd27514925893de5164a3d838a" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 37555 } }
I20260812 06:19:01.826483 20595 raft_consensus.cc:385] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:01.826529 20595 raft_consensus.cc:740] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3939befd27514925893de5164a3d838a, State: Initialized, Role: FOLLOWER
I20260812 06:19:01.826673 20595 consensus_queue.cc:260] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [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: "3939befd27514925893de5164a3d838a" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 37555 } }
I20260812 06:19:01.826753 20595 raft_consensus.cc:399] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:01.826813 20595 raft_consensus.cc:493] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:01.826870 20595 raft_consensus.cc:3060] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:01.827689 20595 raft_consensus.cc:515] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "3939befd27514925893de5164a3d838a" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 37555 } }
I20260812 06:19:01.827864 20595 leader_election.cc:304] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [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: 3939befd27514925893de5164a3d838a; no voters: 
I20260812 06:19:01.828104 20595 leader_election.cc:290] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:01.828253 20597 raft_consensus.cc:2804] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:01.828490 20597 raft_consensus.cc:697] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 1 LEADER]: Becoming Leader. State: Replica: 3939befd27514925893de5164a3d838a, State: Running, Role: LEADER
I20260812 06:19:01.828507 20595 ts_tablet_manager.cc:1434] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:19:01.828547 20578 heartbeater.cc:499] Master 127.19.133.126:37033 was elected leader, sending a full tablet report...
I20260812 06:19:01.828727 20597 consensus_queue.cc:237] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [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: "3939befd27514925893de5164a3d838a" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 37555 } }
I20260812 06:19:01.830052 20358 catalog_manager.cc:5719] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a reported cstate change: term changed from 0 to 1, leader changed from <none> to 3939befd27514925893de5164a3d838a (127.19.133.65). New cstate: current_term: 1 leader_uuid: "3939befd27514925893de5164a3d838a" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "3939befd27514925893de5164a3d838a" member_type: VOTER last_known_addr { host: "127.19.133.65" port: 37555 } health_report { overall_health: HEALTHY } } }
I20260812 06:19:01.891746 19989 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.056s	user 0.019s	sys 0.004s
I20260812 06:19:02.044909 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=19.054940
I20260812 06:19:02.209753 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.165s	user 0.115s	sys 0.048s Metrics: {"bytes_written":13251052,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":78,"dirs.run_cpu_time_us":227,"dirs.run_wall_time_us":846,"drs_written":1,"lbm_read_time_us":62,"lbm_reads_lt_1ms":4,"lbm_write_time_us":42218,"lbm_writes_lt_1ms":780,"mutex_wait_us":884,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":768,"update_count":1615}
I20260812 06:19:02.210361 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8): free 20743880 bytes of WAL
I20260812 06:19:02.210595 20488 log_reader.cc:385] T a13070ebb6334c03b2e47ad21f47dbe8: removed 2 log segments from log reader
I20260812 06:19:02.210640 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000001 (ops 1-6)
I20260812 06:19:02.210697 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000002 (ops 7-11)
I20260812 06:19:02.215256 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.005s	user 0.000s	sys 0.002s Metrics: {}
I20260812 06:19:02.215785 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling UndoDeltaBlockGCOp(a13070ebb6334c03b2e47ad21f47dbe8): 16411395 bytes on disk
I20260812 06:19:02.216183 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: UndoDeltaBlockGCOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4}
I20260812 06:19:02.216605 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:02.228101 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.011s	user 0.002s	sys 0.005s Metrics: {"bytes_written":3569334,"delete_count":0,"lbm_write_time_us":3463,"lbm_writes_lt_1ms":90,"reinsert_count":0,"update_count":435}
I20260812 06:19:02.228585 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:02.238423 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3806,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:02.238785 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:02.443020 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.204s	user 0.125s	sys 0.075s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774791,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":675,"lbm_read_time_us":13135,"lbm_reads_lt_1ms":569,"lbm_write_time_us":34817,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6656,"thread_start_us":333,"threads_started":5,"update_count":2500}
I20260812 06:19:02.443698 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:02.498751 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.055s	user 0.040s	sys 0.008s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22261,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:02.499357 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:02.510129 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4204,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:02.510768 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:02.686524 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.176s	user 0.116s	sys 0.053s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":524,"lbm_read_time_us":11599,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33256,"lbm_writes_lt_1ms":543,"mutex_wait_us":68,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10624,"update_count":2500}
I20260812 06:19:02.687112 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:02.746788 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.060s	user 0.039s	sys 0.012s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23653,"lbm_writes_lt_1ms":403,"mutex_wait_us":16,"reinsert_count":0,"update_count":2000}
I20260812 06:19:02.747223 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:02.757845 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4178,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:02.758396 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:02.917366 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.159s	user 0.115s	sys 0.031s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":433,"lbm_read_time_us":10533,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31469,"lbm_writes_lt_1ms":543,"mutex_wait_us":78,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2500}
I20260812 06:19:02.918035 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:02.970255 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.052s	user 0.023s	sys 0.028s Metrics: {"bytes_written":16409908,"delete_count":0,"lbm_write_time_us":25102,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:19:02.970734 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:02.988406 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.018s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6868,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:02.988907 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:03.146929 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.158s	user 0.102s	sys 0.051s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774695,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":158,"lbm_read_time_us":11321,"lbm_reads_lt_1ms":568,"lbm_write_time_us":31264,"lbm_writes_lt_1ms":543,"mutex_wait_us":42,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":21888,"update_count":2500}
I20260812 06:19:03.147622 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:03.200049 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.052s	user 0.032s	sys 0.017s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24691,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:19:03.200711 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:03.219179 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.018s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6609,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:03.219864 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:03.382714 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.163s	user 0.111s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":327,"lbm_read_time_us":10541,"lbm_reads_lt_1ms":564,"lbm_write_time_us":28879,"lbm_writes_lt_1ms":543,"mutex_wait_us":26,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:19:03.383291 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:03.452236 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.069s	user 0.027s	sys 0.023s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":24371,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:03.452847 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:03.468755 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.016s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6030,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:03.469246 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:03.499727 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.030s	user 0.028s	sys 0.000s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":67,"dirs.run_cpu_time_us":229,"dirs.run_wall_time_us":1445,"drs_written":1,"lbm_read_time_us":36,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1954,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:19:03.500406 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8): free 120553329 bytes of WAL
I20260812 06:19:03.500674 20488 log_reader.cc:385] T a13070ebb6334c03b2e47ad21f47dbe8: removed 12 log segments from log reader
I20260812 06:19:03.500744 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000003 (ops 12-16)
I20260812 06:19:03.500798 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000004 (ops 17-20)
I20260812 06:19:03.500856 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000005 (ops 21-25)
I20260812 06:19:03.500898 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000006 (ops 26-30)
I20260812 06:19:03.500939 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000007 (ops 31-35)
I20260812 06:19:03.500976 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000008 (ops 36-40)
I20260812 06:19:03.501013 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000009 (ops 41-45)
I20260812 06:19:03.501050 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000010 (ops 46-50)
I20260812 06:19:03.501087 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000011 (ops 51-55)
I20260812 06:19:03.501124 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000012 (ops 56-60)
I20260812 06:19:03.501159 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000013 (ops 61-64)
I20260812 06:19:03.501199 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000014 (ops 65-69)
I20260812 06:19:03.529840 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.029s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:19:03.530332 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling UndoDeltaBlockGCOp(a13070ebb6334c03b2e47ad21f47dbe8): 472 bytes on disk
I20260812 06:19:03.530880 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: UndoDeltaBlockGCOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":74,"lbm_reads_lt_1ms":4}
I20260812 06:19:03.531430 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=3.181125
I20260812 06:19:03.550664 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.019s	user 0.017s	sys 0.000s Metrics: {"bytes_written":5046218,"delete_count":0,"lbm_write_time_us":7633,"lbm_writes_lt_1ms":126,"reinsert_count":0,"update_count":615}
I20260812 06:19:03.551079 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.196750
I20260812 06:19:03.563969 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.013s	user 0.007s	sys 0.003s Metrics: {"bytes_written":3159080,"delete_count":0,"lbm_write_time_us":4666,"lbm_writes_lt_1ms":80,"reinsert_count":0,"update_count":385}
I20260812 06:19:03.564728 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:03.815181 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.250s	user 0.173s	sys 0.069s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979728,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":270,"lbm_read_time_us":19917,"lbm_reads_lt_1ms":774,"lbm_write_time_us":43690,"lbm_writes_lt_1ms":743,"mutex_wait_us":22,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":25472,"thread_start_us":89,"threads_started":1,"update_count":3500}
I20260812 06:19:03.815896 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=18.063937
I20260812 06:19:03.881096 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.065s	user 0.048s	sys 0.016s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":29633,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:03.881656 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:03.894964 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.013s	user 0.002s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5146,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:03.895498 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:04.069659 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.174s	user 0.139s	sys 0.034s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877102,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":190,"lbm_read_time_us":14300,"lbm_reads_lt_1ms":672,"lbm_write_time_us":34025,"lbm_writes_lt_1ms":643,"mutex_wait_us":46,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":18688,"update_count":3000}
I20260812 06:19:04.071456 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:04.124356 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.053s	user 0.030s	sys 0.019s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":25809,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:19:04.125167 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:04.146255 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.021s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6173,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:04.146731 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:04.157626 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4215,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:04.158120 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:04.336406 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.178s	user 0.134s	sys 0.044s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877219,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":556,"lbm_read_time_us":13179,"lbm_reads_lt_1ms":673,"lbm_write_time_us":36915,"lbm_writes_lt_1ms":643,"mutex_wait_us":285,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":13696,"update_count":3000}
I20260812 06:19:04.337183 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:04.398463 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.061s	user 0.039s	sys 0.019s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":27224,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:19:04.399098 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:04.423800 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.024s	user 0.003s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5037,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:04.424258 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:04.434671 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.010s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4081,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:04.435177 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:04.601279 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.166s	user 0.127s	sys 0.038s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877219,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":346,"lbm_read_time_us":13221,"lbm_reads_lt_1ms":673,"lbm_write_time_us":33640,"lbm_writes_lt_1ms":643,"mutex_wait_us":36,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":3000}
I20260812 06:19:04.601859 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:04.663795 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.062s	user 0.029s	sys 0.029s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":26791,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:04.664350 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:04.686225 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.022s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6887,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:04.686682 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:04.848750 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.162s	user 0.103s	sys 0.055s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774692,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":378,"lbm_read_time_us":9523,"lbm_reads_lt_1ms":564,"lbm_write_time_us":32971,"lbm_writes_lt_1ms":543,"mutex_wait_us":26,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":115328,"update_count":2500}
I20260812 06:19:04.849349 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:04.911233 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.062s	user 0.028s	sys 0.021s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23256,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:04.911751 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:04.923179 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4343,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:04.923764 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:04.952044 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.028s	user 0.027s	sys 0.000s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":50,"dirs.run_cpu_time_us":214,"dirs.run_wall_time_us":1263,"drs_written":1,"lbm_read_time_us":50,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1893,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:19:04.952787 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8): free 121006498 bytes of WAL
I20260812 06:19:04.953013 20488 log_reader.cc:385] T a13070ebb6334c03b2e47ad21f47dbe8: removed 12 log segments from log reader
I20260812 06:19:04.953063 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000015 (ops 70-74)
I20260812 06:19:04.953090 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000016 (ops 75-79)
I20260812 06:19:04.953157 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000017 (ops 80-84)
I20260812 06:19:04.953202 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000018 (ops 85-88)
I20260812 06:19:04.953243 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000019 (ops 89-93)
I20260812 06:19:04.953295 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000020 (ops 94-98)
I20260812 06:19:04.953339 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000021 (ops 99-103)
I20260812 06:19:04.953379 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000022 (ops 104-108)
I20260812 06:19:04.953420 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000023 (ops 109-113)
I20260812 06:19:04.953464 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000024 (ops 114-118)
I20260812 06:19:04.953504 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000025 (ops 119-123)
I20260812 06:19:04.953543 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000026 (ops 124-128)
I20260812 06:19:04.982434 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.029s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:19:04.982859 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling UndoDeltaBlockGCOp(a13070ebb6334c03b2e47ad21f47dbe8): 473 bytes on disk
I20260812 06:19:04.983325 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: UndoDeltaBlockGCOp(a13070ebb6334c03b2e47ad21f47dbe8) 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:19:04.983824 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=3.181125
I20260812 06:19:05.003592 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.020s	user 0.016s	sys 0.000s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":7379,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:19:05.004022 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:05.013661 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3661,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:05.014114 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:05.241139 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.227s	user 0.147s	sys 0.068s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979737,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":639,"lbm_read_time_us":16772,"lbm_reads_lt_1ms":774,"lbm_write_time_us":40460,"lbm_writes_lt_1ms":743,"mutex_wait_us":77,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":12928,"thread_start_us":71,"threads_started":1,"update_count":3500}
I20260812 06:19:05.241645 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=18.063937
I20260812 06:19:05.314553 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.073s	user 0.027s	sys 0.044s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":33255,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:19:05.315084 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=3.181125
I20260812 06:19:05.336078 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.021s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4512912,"delete_count":0,"lbm_write_time_us":4818,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:19:05.336596 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:05.346186 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.009s	user 0.003s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3801,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:05.346745 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:05.535326 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.188s	user 0.168s	sys 0.020s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979633,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":141,"lbm_read_time_us":13953,"lbm_reads_lt_1ms":773,"lbm_write_time_us":39459,"lbm_writes_lt_1ms":743,"mutex_wait_us":27,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":28032,"update_count":3500}
I20260812 06:19:05.536046 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:05.593616 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.057s	user 0.035s	sys 0.016s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23931,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:19:05.594107 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=3.181125
I20260812 06:19:05.606858 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.013s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":4708,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:19:05.607334 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:05.621218 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.014s	user 0.005s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5432,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:05.621683 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:05.787441 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.166s	user 0.125s	sys 0.040s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877207,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":268,"lbm_read_time_us":12396,"lbm_reads_lt_1ms":673,"lbm_write_time_us":32660,"lbm_writes_lt_1ms":643,"mutex_wait_us":26,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":7168,"update_count":3000}
I20260812 06:19:05.788223 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:05.845752 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.057s	user 0.033s	sys 0.023s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":25791,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:19:05.846365 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:05.865059 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.018s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6790,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:05.865502 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:06.047359 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.182s	user 0.122s	sys 0.046s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":318,"lbm_read_time_us":11820,"lbm_reads_lt_1ms":568,"lbm_write_time_us":31204,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7552,"update_count":2500}
I20260812 06:19:06.048049 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:06.122152 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.074s	user 0.037s	sys 0.023s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":27812,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:19:06.122612 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:06.137176 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.014s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5608,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:06.137727 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:06.317050 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.179s	user 0.125s	sys 0.054s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":772,"lbm_read_time_us":13789,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29578,"lbm_writes_lt_1ms":543,"mutex_wait_us":273,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":12672,"update_count":2500}
I20260812 06:19:06.317597 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:06.376084 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.058s	user 0.027s	sys 0.027s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":26065,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:06.376670 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:06.387758 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4320,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:06.388599 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:06.420022 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushMRSOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.031s	user 0.026s	sys 0.001s Metrics: {"bytes_written":1234477,"cfile_init":1,"dirs.queue_time_us":75,"dirs.run_cpu_time_us":253,"dirs.run_wall_time_us":1440,"drs_written":1,"lbm_read_time_us":88,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1630,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30,"spinlock_wait_cycles":2432}
I20260812 06:19:06.420749 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8): free 127961371 bytes of WAL
I20260812 06:19:06.420966 20488 log_reader.cc:385] T a13070ebb6334c03b2e47ad21f47dbe8: removed 12 log segments from log reader
I20260812 06:19:06.421010 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000027 (ops 129-133)
I20260812 06:19:06.421037 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000028 (ops 134-138)
I20260812 06:19:06.421104 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000029 (ops 139-143)
I20260812 06:19:06.421135 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000030 (ops 144-148)
I20260812 06:19:06.421176 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000031 (ops 149-153)
I20260812 06:19:06.421216 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000032 (ops 154-158)
I20260812 06:19:06.421254 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000033 (ops 159-163)
I20260812 06:19:06.421293 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000034 (ops 164-168)
I20260812 06:19:06.421334 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000035 (ops 169-173)
I20260812 06:19:06.421372 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000036 (ops 174-178)
I20260812 06:19:06.421411 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000037 (ops 179-183)
I20260812 06:19:06.421450 20488 log.cc:1079] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: Deleting log segment in path: /tmp/dist-test-taskTyGER6/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515536199050-19989-0/minicluster-data/ts-0-root/wals/a13070ebb6334c03b2e47ad21f47dbe8/wal-000000038 (ops 184-188)
I20260812 06:19:06.451064 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: LogGCOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.030s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:19:06.451470 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=3.181125
I20260812 06:19:06.463336 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.012s	user 0.000s	sys 0.010s Metrics: {"bytes_written":4594951,"delete_count":0,"lbm_write_time_us":4790,"lbm_writes_lt_1ms":115,"reinsert_count":0,"update_count":560}
I20260812 06:19:06.463760 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=2.188937
I20260812 06:19:06.474292 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.010s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3610355,"delete_count":0,"lbm_write_time_us":3912,"lbm_writes_lt_1ms":91,"reinsert_count":0,"update_count":440}
I20260812 06:19:06.475365 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=1.000000
I20260812 06:19:06.644119 19989 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.752s	user 1.852s	sys 0.095s
I20260812 06:19:06.713012 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: MajorDeltaCompactionOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.237s	user 0.145s	sys 0.091s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979740,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"lbm_read_time_us":16697,"lbm_reads_lt_1ms":770,"lbm_write_time_us":41724,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":2688,"update_count":3500}
I20260812 06:19:06.713544 20579 maintenance_manager.cc:419] P 3939befd27514925893de5164a3d838a: Scheduling FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8): perf score=14.095187
I20260812 06:19:06.743672 19989 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.099s	user 0.001s	sys 0.000s
I20260812 06:19:06.744369 19989 tablet_server.cc:179] TabletServer@127.19.133.65:0 shutting down...
I20260812 06:19:06.759793 20488 maintenance_manager.cc:643] P 3939befd27514925893de5164a3d838a: FlushDeltaMemStoresOp(a13070ebb6334c03b2e47ad21f47dbe8) complete. Timing: real 0.046s	user 0.025s	sys 0.017s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":20982,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:06.760353 19989 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:19:06.760646 19989 tablet_replica.cc:333] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a: stopping tablet replica
I20260812 06:19:06.760854 19989 raft_consensus.cc:2243] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:06.761041 19989 raft_consensus.cc:2272] T a13070ebb6334c03b2e47ad21f47dbe8 P 3939befd27514925893de5164a3d838a [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:06.775492 19989 tablet_server.cc:196] TabletServer@127.19.133.65:0 shutdown complete.
I20260812 06:19:06.778822 19989 master.cc:562] Master@127.19.133.126:37033 shutting down...
I20260812 06:19:06.782378 19989 raft_consensus.cc:2243] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:06.782542 19989 raft_consensus.cc:2272] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:06.782593 19989 tablet_replica.cc:333] T 00000000000000000000000000000000 P b4f5c61badcb4ab8b809d648a8a6bf4f: stopping tablet replica
I20260812 06:19:06.795077 19989 master.cc:584] Master@127.19.133.126:37033 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5208 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10678 ms total)

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