[==========] 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:19:13.845064 24618 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.24.10.190:43455
I20260812 06:19:13.846050 24618 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:19:13.846658 24618 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:13.853519 24626 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:13.853575 24633 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:13.853639 24618 server_base.cc:1061] running on GCE node
W20260812 06:19:13.853834 24625 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:13.854300 24618 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:13.854399 24618 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:13.854430 24618 hybrid_clock.cc:648] HybridClock initialized: now 1786515553854429 us; error 0 us; skew 500 ppm
I20260812 06:19:13.856168 24618 webserver.cc:533] Webserver started at http://127.24.10.190:37997/ using document root <none> and password file <none>
I20260812 06:19:13.856660 24618 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:13.856714 24618 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:13.856901 24618 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:13.858453 24618 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/master-0-root/instance:
uuid: "696d384f1b144edd92c42a3af17724e7"
format_stamp: "Formatted at 2026-08-12 06:19:13 on dist-test-slave-x4qh"
I20260812 06:19:13.861819 24618 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
I20260812 06:19:13.863853 24638 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:13.864869 24618 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:19:13.865010 24618 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/master-0-root
uuid: "696d384f1b144edd92c42a3af17724e7"
format_stamp: "Formatted at 2026-08-12 06:19:13 on dist-test-slave-x4qh"
I20260812 06:19:13.865118 24618 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-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:13.883791 24618 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:13.884475 24618 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:19:13.884670 24618 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:13.892772 24734 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.10.190:43455 every 8 connection(s)
I20260812 06:19:13.892771 24618 rpc_server.cc:307] RPC server started. Bound to: 127.24.10.190:43455
I20260812 06:19:13.895081 24735 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:13.901149 24735 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7: Bootstrap starting.
I20260812 06:19:13.904114 24735 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:13.905102 24735 log.cc:826] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7: Log is configured to *not* fsync() on all Append() calls
I20260812 06:19:13.906810 24735 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7: No bootstrap required, opened a new log
I20260812 06:19:13.909631 24735 raft_consensus.cc:359] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "696d384f1b144edd92c42a3af17724e7" member_type: VOTER }
I20260812 06:19:13.909791 24735 raft_consensus.cc:385] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:13.909842 24735 raft_consensus.cc:740] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 696d384f1b144edd92c42a3af17724e7, State: Initialized, Role: FOLLOWER
I20260812 06:19:13.910461 24735 consensus_queue.cc:260] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [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: "696d384f1b144edd92c42a3af17724e7" member_type: VOTER }
I20260812 06:19:13.910607 24735 raft_consensus.cc:399] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:13.910655 24735 raft_consensus.cc:493] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:13.910738 24735 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:13.911556 24735 raft_consensus.cc:515] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "696d384f1b144edd92c42a3af17724e7" member_type: VOTER }
I20260812 06:19:13.911931 24735 leader_election.cc:304] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [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: 696d384f1b144edd92c42a3af17724e7; no voters: 
I20260812 06:19:13.912205 24735 leader_election.cc:290] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:13.912360 24739 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:13.912617 24739 raft_consensus.cc:697] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 1 LEADER]: Becoming Leader. State: Replica: 696d384f1b144edd92c42a3af17724e7, State: Running, Role: LEADER
I20260812 06:19:13.913026 24739 consensus_queue.cc:237] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [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: "696d384f1b144edd92c42a3af17724e7" member_type: VOTER }
I20260812 06:19:13.913280 24735 sys_catalog.cc:565] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:19:13.915023 24741 sys_catalog.cc:455] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 696d384f1b144edd92c42a3af17724e7. Latest consensus state: current_term: 1 leader_uuid: "696d384f1b144edd92c42a3af17724e7" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "696d384f1b144edd92c42a3af17724e7" member_type: VOTER } }
I20260812 06:19:13.915062 24740 sys_catalog.cc:455] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "696d384f1b144edd92c42a3af17724e7" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "696d384f1b144edd92c42a3af17724e7" member_type: VOTER } }
I20260812 06:19:13.915139 24741 sys_catalog.cc:458] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:13.915169 24740 sys_catalog.cc:458] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:13.915503 24756 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:19:13.915619 24618 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:19:13.917681 24756 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:19:13.922299 24756 catalog_manager.cc:1383] Generated new cluster ID: 76d0b9025ed94952a65cf60c26faf102
I20260812 06:19:13.922379 24756 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:19:13.951232 24756 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:19:13.952440 24756 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:19:13.970147 24756 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7: Generated new TSK 0
I20260812 06:19:13.970927 24756 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:19:13.980343 24618 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:13.983281 24764 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:13.983402 24618 server_base.cc:1061] running on GCE node
W20260812 06:19:13.983547 24767 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:13.983362 24763 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:13.983881 24618 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:13.983925 24618 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:13.983942 24618 hybrid_clock.cc:648] HybridClock initialized: now 1786515553983941 us; error 0 us; skew 500 ppm
I20260812 06:19:13.984913 24618 webserver.cc:533] Webserver started at http://127.24.10.129:40947/ using document root <none> and password file <none>
I20260812 06:19:13.985092 24618 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:13.985140 24618 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:13.985239 24618 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:13.985641 24618 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/instance:
uuid: "05b1b697a2214d04a3d96e99dc39756e"
format_stamp: "Formatted at 2026-08-12 06:19:13 on dist-test-slave-x4qh"
I20260812 06:19:13.987192 24618 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:19:13.988399 24775 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:13.988664 24618 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:19:13.988744 24618 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root
uuid: "05b1b697a2214d04a3d96e99dc39756e"
format_stamp: "Formatted at 2026-08-12 06:19:13 on dist-test-slave-x4qh"
I20260812 06:19:13.988848 24618 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-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:14.000614 24618 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:14.001140 24618 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:14.001703 24618 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:19:14.002604 24618 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:19:14.002656 24618 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:14.002737 24618 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:19:14.002775 24618 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:14.009913 24618 rpc_server.cc:307] RPC server started. Bound to: 127.24.10.129:40829
I20260812 06:19:14.009949 24891 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.10.129:40829 every 8 connection(s)
I20260812 06:19:14.020287 24893 heartbeater.cc:344] Connected to a master server at 127.24.10.190:43455
I20260812 06:19:14.020542 24893 heartbeater.cc:461] Registering TS with master...
I20260812 06:19:14.020998 24893 heartbeater.cc:507] Master 127.24.10.190:43455 requested a full tablet report, sending...
I20260812 06:19:14.022380 24670 ts_manager.cc:194] Registered new tserver with Master: 05b1b697a2214d04a3d96e99dc39756e (127.24.10.129:40829)
I20260812 06:19:14.022944 24618 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.012363724s
I20260812 06:19:14.023901 24670 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:42656
I20260812 06:19:14.032637 24670 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:42666:
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:14.047287 24816 tablet_service.cc:1511] Processing CreateTablet for tablet 55de2418ac064cf2ac6db7cbba2ad09e (DEFAULT_TABLE table=heavy-update-compaction-test [id=1758a5dfc6f94bb5b9697a8daf87e2a0]), partition=
I20260812 06:19:14.047910 24816 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 55de2418ac064cf2ac6db7cbba2ad09e. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:14.051119 24915 tablet_bootstrap.cc:492] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Bootstrap starting.
I20260812 06:19:14.052309 24915 tablet_bootstrap.cc:654] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:14.054011 24915 tablet_bootstrap.cc:492] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: No bootstrap required, opened a new log
I20260812 06:19:14.054150 24915 ts_tablet_manager.cc:1403] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:19:14.054809 24915 raft_consensus.cc:359] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "05b1b697a2214d04a3d96e99dc39756e" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 40829 } }
I20260812 06:19:14.054972 24915 raft_consensus.cc:385] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:14.055032 24915 raft_consensus.cc:740] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 05b1b697a2214d04a3d96e99dc39756e, State: Initialized, Role: FOLLOWER
I20260812 06:19:14.055200 24915 consensus_queue.cc:260] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [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: "05b1b697a2214d04a3d96e99dc39756e" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 40829 } }
I20260812 06:19:14.055344 24915 raft_consensus.cc:399] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:14.055464 24915 raft_consensus.cc:493] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:14.055546 24915 raft_consensus.cc:3060] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:14.056452 24915 raft_consensus.cc:515] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "05b1b697a2214d04a3d96e99dc39756e" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 40829 } }
I20260812 06:19:14.056666 24915 leader_election.cc:304] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [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: 05b1b697a2214d04a3d96e99dc39756e; no voters: 
I20260812 06:19:14.056951 24915 leader_election.cc:290] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:14.057075 24918 raft_consensus.cc:2804] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:14.057385 24918 raft_consensus.cc:697] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 1 LEADER]: Becoming Leader. State: Replica: 05b1b697a2214d04a3d96e99dc39756e, State: Running, Role: LEADER
I20260812 06:19:14.057410 24915 ts_tablet_manager.cc:1434] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Time spent starting tablet: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:19:14.057595 24893 heartbeater.cc:499] Master 127.24.10.190:43455 was elected leader, sending a full tablet report...
I20260812 06:19:14.057639 24918 consensus_queue.cc:237] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [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: "05b1b697a2214d04a3d96e99dc39756e" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 40829 } }
I20260812 06:19:14.060483 24670 catalog_manager.cc:5719] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e reported cstate change: term changed from 0 to 1, leader changed from <none> to 05b1b697a2214d04a3d96e99dc39756e (127.24.10.129). New cstate: current_term: 1 leader_uuid: "05b1b697a2214d04a3d96e99dc39756e" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "05b1b697a2214d04a3d96e99dc39756e" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 40829 } health_report { overall_health: HEALTHY } } }
I20260812 06:19:14.134268 24618 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.066s	user 0.015s	sys 0.021s
I20260812 06:19:14.261107 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=19.054940
I20260812 06:19:14.417271 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.156s	user 0.117s	sys 0.036s Metrics: {"bytes_written":8656350,"cfile_init":1,"compiler_manager_pool.queue_time_us":244,"delete_count":0,"dirs.queue_time_us":36,"dirs.run_cpu_time_us":242,"dirs.run_wall_time_us":972,"drs_written":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4,"lbm_write_time_us":37261,"lbm_writes_lt_1ms":668,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":194304,"thread_start_us":143,"threads_started":1,"update_count":1055}
I20260812 06:19:14.418305 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e): free 20743880 bytes of WAL
I20260812 06:19:14.418609 24783 log_reader.cc:385] T 55de2418ac064cf2ac6db7cbba2ad09e: removed 2 log segments from log reader
I20260812 06:19:14.418692 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000001 (ops 1-6)
I20260812 06:19:14.418763 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000002 (ops 7-11)
I20260812 06:19:14.423036 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.005s	user 0.000s	sys 0.001s Metrics: {}
I20260812 06:19:14.423434 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e): 16411391 bytes on disk
I20260812 06:19:14.423979 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4}
I20260812 06:19:14.424439 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:14.440065 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.015s	user 0.010s	sys 0.004s Metrics: {"bytes_written":3651380,"delete_count":0,"lbm_write_time_us":5372,"lbm_writes_lt_1ms":92,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":445}
I20260812 06:19:14.440557 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:14.558068 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.117s	user 0.083s	sys 0.024s Metrics: {"cfile_cache_miss":332,"cfile_cache_miss_bytes":16569858,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1227,"lbm_read_time_us":5947,"lbm_reads_lt_1ms":364,"lbm_write_time_us":20230,"lbm_writes_lt_1ms":343,"peak_mem_usage":38262756,"reinsert_count":0,"spinlock_wait_cycles":3840,"thread_start_us":546,"threads_started":5,"update_count":1500}
I20260812 06:19:14.558763 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=10.126437
I20260812 06:19:14.603694 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.045s	user 0.026s	sys 0.012s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16842,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:14.604141 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:14.614538 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3939,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:14.615031 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:14.750028 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.135s	user 0.103s	sys 0.028s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":260,"lbm_read_time_us":8652,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26114,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:19:14.750528 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=10.126437
I20260812 06:19:14.806615 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.056s	user 0.020s	sys 0.023s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":17175,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:14.807108 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:14.817840 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4269,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:14.818269 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:14.966668 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.148s	user 0.108s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":209,"lbm_read_time_us":11225,"lbm_reads_lt_1ms":472,"lbm_write_time_us":23520,"lbm_writes_lt_1ms":443,"mutex_wait_us":22,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":58112,"update_count":2000}
I20260812 06:19:14.967232 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=10.126437
I20260812 06:19:15.014950 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.048s	user 0.010s	sys 0.023s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16445,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:15.015568 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:15.027171 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4163,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:15.027731 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:15.149044 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.121s	user 0.099s	sys 0.021s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1672,"lbm_read_time_us":8594,"lbm_reads_lt_1ms":472,"lbm_write_time_us":23222,"lbm_writes_lt_1ms":443,"mutex_wait_us":576,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":19840,"update_count":2000}
I20260812 06:19:15.149904 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=10.126437
I20260812 06:19:15.191639 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.041s	user 0.029s	sys 0.011s Metrics: {"bytes_written":12307493,"delete_count":0,"lbm_write_time_us":18092,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:19:15.192519 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:15.209120 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.016s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5973,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:15.209690 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:15.352442 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.143s	user 0.120s	sys 0.012s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672279,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":379,"lbm_read_time_us":8174,"lbm_reads_lt_1ms":464,"lbm_write_time_us":26478,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:19:15.352960 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:15.408023 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.055s	user 0.031s	sys 0.024s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":25415,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:19:15.408493 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:15.430680 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.022s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5629,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:15.431269 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:15.618846 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.187s	user 0.103s	sys 0.073s 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":597,"lbm_read_time_us":11369,"lbm_reads_lt_1ms":564,"lbm_write_time_us":37926,"lbm_writes_lt_1ms":543,"mutex_wait_us":332,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2500}
I20260812 06:19:15.619527 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:15.676018 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.056s	user 0.035s	sys 0.021s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":24810,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:15.676671 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:15.736635 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.060s	user 0.035s	sys 0.000s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":81,"dirs.run_cpu_time_us":197,"dirs.run_wall_time_us":1558,"drs_written":1,"lbm_read_time_us":103,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1531,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:19:15.737474 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e): free 111786258 bytes of WAL
I20260812 06:19:15.737753 24783 log_reader.cc:385] T 55de2418ac064cf2ac6db7cbba2ad09e: removed 11 log segments from log reader
I20260812 06:19:15.737804 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000003 (ops 12-16)
I20260812 06:19:15.737834 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000004 (ops 17-21)
I20260812 06:19:15.737901 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000005 (ops 22-26)
I20260812 06:19:15.737965 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000006 (ops 27-31)
I20260812 06:19:15.738013 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000007 (ops 32-36)
I20260812 06:19:15.738070 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000008 (ops 37-41)
I20260812 06:19:15.738121 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000009 (ops 42-46)
I20260812 06:19:15.738158 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000010 (ops 47-50)
I20260812 06:19:15.738193 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000011 (ops 51-55)
I20260812 06:19:15.738231 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000012 (ops 56-60)
I20260812 06:19:15.738269 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000013 (ops 61-64)
I20260812 06:19:15.762598 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.025s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:19:15.763129 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e): 463 bytes on disk
I20260812 06:19:15.763868 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":109,"lbm_reads_lt_1ms":4}
I20260812 06:19:15.764457 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=6.157687
I20260812 06:19:15.785655 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.021s	user 0.010s	sys 0.009s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":9000,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:19:15.786103 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e): free 8767118 bytes of WAL
I20260812 06:19:15.786322 24783 log_reader.cc:385] T 55de2418ac064cf2ac6db7cbba2ad09e: removed 1 log segments from log reader
I20260812 06:19:15.786376 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000014 (ops 65-69)
I20260812 06:19:15.788311 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.002s	user 0.000s	sys 0.001s Metrics: {}
I20260812 06:19:15.788636 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:15.809738 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.021s	user 0.011s	sys 0.001s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5761,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:15.810278 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:16.039248 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.229s	user 0.131s	sys 0.093s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979633,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":8664,"lbm_read_time_us":17064,"lbm_reads_lt_1ms":765,"lbm_write_time_us":43076,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":742,"mutex_wait_us":2718,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":7680,"thread_start_us":91,"threads_started":1,"update_count":3500}
I20260812 06:19:16.040098 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=18.063937
I20260812 06:19:16.100716 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.060s	user 0.041s	sys 0.018s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":27089,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:16.101171 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:16.119266 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.018s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6558,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:16.119789 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:16.282500 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.163s	user 0.119s	sys 0.043s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877104,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":536,"lbm_read_time_us":12594,"lbm_reads_lt_1ms":664,"lbm_write_time_us":32302,"lbm_writes_lt_1ms":643,"mutex_wait_us":309,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":78720,"update_count":3000}
I20260812 06:19:16.283152 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:16.329216 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.046s	user 0.028s	sys 0.015s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":20086,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:16.330009 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:16.348117 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.018s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6838,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:16.348606 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:16.506493 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.158s	user 0.119s	sys 0.033s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774691,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":276,"lbm_read_time_us":9179,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30468,"lbm_writes_lt_1ms":543,"mutex_wait_us":55,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10240,"update_count":2500}
I20260812 06:19:16.507259 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:16.567149 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.060s	user 0.037s	sys 0.008s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":21825,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:16.567691 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:16.579531 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.012s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4055,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:16.580150 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:16.747551 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.167s	user 0.098s	sys 0.069s 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":791,"lbm_read_time_us":11238,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31501,"lbm_writes_lt_1ms":543,"mutex_wait_us":302,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13312,"update_count":2500}
I20260812 06:19:16.748095 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:16.804971 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.057s	user 0.031s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23099,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:16.805524 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:16.816547 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4253,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:16.817062 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:16.988931 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.172s	user 0.119s	sys 0.051s 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":1145,"lbm_read_time_us":13069,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30203,"lbm_writes_lt_1ms":543,"mutex_wait_us":302,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13696,"update_count":2500}
I20260812 06:19:16.989464 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:17.054636 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.065s	user 0.039s	sys 0.023s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23751,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:17.055305 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:17.067533 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.012s	user 0.007s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4750,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.068372 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:17.105036 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.036s	user 0.029s	sys 0.004s Metrics: {"bytes_written":1152514,"cfile_init":1,"dirs.queue_time_us":80,"dirs.run_cpu_time_us":252,"dirs.run_wall_time_us":1187,"drs_written":1,"lbm_read_time_us":66,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2150,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:19:17.105780 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e): free 112239265 bytes of WAL
I20260812 06:19:17.106011 24783 log_reader.cc:385] T 55de2418ac064cf2ac6db7cbba2ad09e: removed 11 log segments from log reader
I20260812 06:19:17.106060 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000015 (ops 70-74)
I20260812 06:19:17.106088 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000016 (ops 75-79)
I20260812 06:19:17.106148 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000017 (ops 80-84)
I20260812 06:19:17.106192 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000018 (ops 85-89)
I20260812 06:19:17.106235 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000019 (ops 90-94)
I20260812 06:19:17.106276 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000020 (ops 95-99)
I20260812 06:19:17.106315 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000021 (ops 100-104)
I20260812 06:19:17.106355 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000022 (ops 105-108)
I20260812 06:19:17.106395 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000023 (ops 109-113)
I20260812 06:19:17.106434 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000024 (ops 114-118)
I20260812 06:19:17.106477 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000025 (ops 119-123)
I20260812 06:19:17.130124 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.024s	user 0.000s	sys 0.021s Metrics: {}
I20260812 06:19:17.130537 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e): 447 bytes on disk
I20260812 06:19:17.130987 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":54,"lbm_reads_lt_1ms":4}
I20260812 06:19:17.131645 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:17.152698 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.021s	user 0.006s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6423,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.153251 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:17.163970 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.010s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3958,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.164507 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:17.377652 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.213s	user 0.149s	sys 0.064s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979750,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":422,"lbm_read_time_us":15965,"lbm_reads_lt_1ms":774,"lbm_write_time_us":37900,"lbm_writes_lt_1ms":743,"mutex_wait_us":76,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":2432,"thread_start_us":77,"threads_started":1,"update_count":3500}
I20260812 06:19:17.378260 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=18.063937
I20260812 06:19:17.443888 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.065s	user 0.045s	sys 0.020s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":29180,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:19:17.444478 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:17.469622 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.025s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5331,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.470074 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:17.480079 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.010s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3902,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.480505 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:17.678807 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.198s	user 0.139s	sys 0.044s 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":543,"lbm_read_time_us":14433,"lbm_reads_lt_1ms":773,"lbm_write_time_us":38682,"lbm_writes_lt_1ms":743,"mutex_wait_us":59,"peak_mem_usage":87969044,"reinsert_count":0,"update_count":3500}
I20260812 06:19:17.679317 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=18.063937
I20260812 06:19:17.735114 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.056s	user 0.026s	sys 0.026s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":25390,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:17.735649 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:17.752012 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.016s	user 0.011s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6459,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.752562 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:17.925432 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.173s	user 0.120s	sys 0.052s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877103,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":233,"lbm_read_time_us":11953,"lbm_reads_lt_1ms":664,"lbm_write_time_us":36302,"lbm_writes_lt_1ms":643,"mutex_wait_us":55,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":3000}
I20260812 06:19:17.926111 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:17.978071 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.052s	user 0.021s	sys 0.027s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22717,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:17.978648 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:17.991873 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.013s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5435,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.992321 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:18.159193 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.167s	user 0.116s	sys 0.041s 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":193,"lbm_read_time_us":11670,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30734,"lbm_writes_lt_1ms":543,"mutex_wait_us":35,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1536,"update_count":2500}
I20260812 06:19:18.159988 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=14.095187
I20260812 06:19:18.205437 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.045s	user 0.028s	sys 0.015s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":20081,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:18.205968 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:18.359589 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.153s	user 0.105s	sys 0.039s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672156,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":3872,"lbm_read_time_us":10113,"lbm_reads_lt_1ms":463,"lbm_write_time_us":24926,"lbm_writes_lt_1ms":443,"mutex_wait_us":3058,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":2000}
I20260812 06:19:18.360288 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=11.118625
I20260812 06:19:18.398181 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.038s	user 0.013s	sys 0.022s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":16978,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:19:18.398753 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:18.420077 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.021s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6018,"lbm_writes_lt_1ms":103,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":500}
I20260812 06:19:18.420569 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:18.430240 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.009s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3681,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:18.430673 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:18.470361 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushMRSOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.040s	user 0.027s	sys 0.000s Metrics: {"bytes_written":1193505,"cfile_init":1,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":191,"dirs.run_wall_time_us":1183,"drs_written":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2018,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:19:18.471120 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e): free 120100609 bytes of WAL
I20260812 06:19:18.471351 24783 log_reader.cc:385] T 55de2418ac064cf2ac6db7cbba2ad09e: removed 12 log segments from log reader
I20260812 06:19:18.471469 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000026 (ops 124-128)
I20260812 06:19:18.471520 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000027 (ops 129-133)
I20260812 06:19:18.471560 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000028 (ops 134-138)
I20260812 06:19:18.471596 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000029 (ops 139-143)
I20260812 06:19:18.471643 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000030 (ops 144-148)
I20260812 06:19:18.471683 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000031 (ops 149-152)
I20260812 06:19:18.471724 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000032 (ops 153-157)
I20260812 06:19:18.471762 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000033 (ops 158-162)
I20260812 06:19:18.471801 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000034 (ops 163-166)
I20260812 06:19:18.471841 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000035 (ops 167-171)
I20260812 06:19:18.471881 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000036 (ops 172-176)
I20260812 06:19:18.471920 24783 log.cc:1079] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/55de2418ac064cf2ac6db7cbba2ad09e/wal-000000037 (ops 177-180)
I20260812 06:19:18.496901 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: LogGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.026s	user 0.000s	sys 0.024s Metrics: {}
I20260812 06:19:18.497367 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e): 462 bytes on disk
I20260812 06:19:18.497808 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: UndoDeltaBlockGCOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":64,"lbm_reads_lt_1ms":4}
I20260812 06:19:18.498430 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:18.513378 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.015s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4373,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:18.513775 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=2.188937
I20260812 06:19:18.524144 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4010,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:18.524869 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:18.747951 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.223s	user 0.150s	sys 0.072s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":32979860,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":569,"lbm_read_time_us":14309,"lbm_reads_lt_1ms":775,"lbm_write_time_us":37595,"lbm_writes_lt_1ms":743,"mutex_wait_us":27,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":68352,"thread_start_us":83,"threads_started":1,"update_count":3500}
I20260812 06:19:18.748721 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=18.063937
I20260812 06:19:18.801710 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.053s	user 0.034s	sys 0.016s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":23878,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:18.802186 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=1.000000
I20260812 06:19:18.916981 24618 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.783s	user 1.830s	sys 0.124s
I20260812 06:19:18.982827 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: MajorDeltaCompactionOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.180s	user 0.136s	sys 0.044s Metrics: {"cfile_cache_miss":531,"cfile_cache_miss_bytes":24774573,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":421,"lbm_read_time_us":11144,"lbm_reads_lt_1ms":559,"lbm_write_time_us":31955,"lbm_writes_lt_1ms":543,"mutex_wait_us":28,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":72192,"update_count":2500}
I20260812 06:19:18.983543 24895 maintenance_manager.cc:419] P 05b1b697a2214d04a3d96e99dc39756e: Scheduling FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e): perf score=10.126437
I20260812 06:19:18.992467 24618 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.075s	user 0.003s	sys 0.000s
I20260812 06:19:18.993112 24618 tablet_server.cc:179] TabletServer@127.24.10.129:0 shutting down...
I20260812 06:19:19.017230 24783 maintenance_manager.cc:643] P 05b1b697a2214d04a3d96e99dc39756e: FlushDeltaMemStoresOp(55de2418ac064cf2ac6db7cbba2ad09e) complete. Timing: real 0.033s	user 0.025s	sys 0.006s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15175,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:19.018134 24618 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:19:19.018566 24618 tablet_replica.cc:333] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e: stopping tablet replica
I20260812 06:19:19.018872 24618 raft_consensus.cc:2243] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:19.019117 24618 raft_consensus.cc:2272] T 55de2418ac064cf2ac6db7cbba2ad09e P 05b1b697a2214d04a3d96e99dc39756e [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:19.035102 24618 tablet_server.cc:196] TabletServer@127.24.10.129:0 shutdown complete.
I20260812 06:19:19.039976 24618 master.cc:562] Master@127.24.10.190:43455 shutting down...
I20260812 06:19:19.043805 24618 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:19.043972 24618 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:19.044026 24618 tablet_replica.cc:333] T 00000000000000000000000000000000 P 696d384f1b144edd92c42a3af17724e7: stopping tablet replica
I20260812 06:19:19.056612 24618 master.cc:584] Master@127.24.10.190:43455 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5310 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:19:19.167805 24618 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.24.10.190:41991
I20260812 06:19:19.168187 24618 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:19.170737 24948 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:19.170841 24942 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:19.170899 24618 server_base.cc:1061] running on GCE node
W20260812 06:19:19.171030 24943 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:19.171257 24618 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:19.171303 24618 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:19.171319 24618 hybrid_clock.cc:648] HybridClock initialized: now 1786515559171319 us; error 0 us; skew 500 ppm
I20260812 06:19:19.172274 24618 webserver.cc:533] Webserver started at http://127.24.10.190:45677/ using document root <none> and password file <none>
I20260812 06:19:19.172473 24618 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:19.172524 24618 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:19.172631 24618 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:19.173085 24618 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/master-0-root/instance:
uuid: "063ffa7e35054c26ad9114f669c8e977"
format_stamp: "Formatted at 2026-08-12 06:19:19 on dist-test-slave-x4qh"
I20260812 06:19:19.174574 24618 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.002s
I20260812 06:19:19.175513 24953 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:19.175745 24618 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:19:19.175836 24618 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/master-0-root
uuid: "063ffa7e35054c26ad9114f669c8e977"
format_stamp: "Formatted at 2026-08-12 06:19:19 on dist-test-slave-x4qh"
I20260812 06:19:19.175920 24618 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-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:19.192714 24618 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:19.193148 24618 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:19.197623 24618 rpc_server.cc:307] RPC server started. Bound to: 127.24.10.190:41991
I20260812 06:19:19.199496 25038 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.10.190:41991 every 8 connection(s)
I20260812 06:19:19.201471 25039 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:19.204111 25039 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977: Bootstrap starting.
I20260812 06:19:19.204898 25039 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:19.205993 25039 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977: No bootstrap required, opened a new log
I20260812 06:19:19.206416 25039 raft_consensus.cc:359] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "063ffa7e35054c26ad9114f669c8e977" member_type: VOTER }
I20260812 06:19:19.206511 25039 raft_consensus.cc:385] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:19.206537 25039 raft_consensus.cc:740] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 063ffa7e35054c26ad9114f669c8e977, State: Initialized, Role: FOLLOWER
I20260812 06:19:19.206703 25039 consensus_queue.cc:260] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [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: "063ffa7e35054c26ad9114f669c8e977" member_type: VOTER }
I20260812 06:19:19.206777 25039 raft_consensus.cc:399] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:19.206807 25039 raft_consensus.cc:493] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:19.206844 25039 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:19.207623 25039 raft_consensus.cc:515] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "063ffa7e35054c26ad9114f669c8e977" member_type: VOTER }
I20260812 06:19:19.207756 25039 leader_election.cc:304] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [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: 063ffa7e35054c26ad9114f669c8e977; no voters: 
I20260812 06:19:19.207937 25039 leader_election.cc:290] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:19.208072 25042 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:19.208312 25042 raft_consensus.cc:697] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 1 LEADER]: Becoming Leader. State: Replica: 063ffa7e35054c26ad9114f669c8e977, State: Running, Role: LEADER
I20260812 06:19:19.208460 25042 consensus_queue.cc:237] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [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: "063ffa7e35054c26ad9114f669c8e977" member_type: VOTER }
I20260812 06:19:19.208523 25039 sys_catalog.cc:565] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:19:19.208904 25043 sys_catalog.cc:455] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "063ffa7e35054c26ad9114f669c8e977" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "063ffa7e35054c26ad9114f669c8e977" member_type: VOTER } }
I20260812 06:19:19.209015 25043 sys_catalog.cc:458] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:19.209255 25045 sys_catalog.cc:455] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 063ffa7e35054c26ad9114f669c8e977. Latest consensus state: current_term: 1 leader_uuid: "063ffa7e35054c26ad9114f669c8e977" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "063ffa7e35054c26ad9114f669c8e977" member_type: VOTER } }
I20260812 06:19:19.209365 25045 sys_catalog.cc:458] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:19.209304 25048 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:19:19.210770 25048 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:19:19.211024 24618 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:19:19.212865 25048 catalog_manager.cc:1383] Generated new cluster ID: cf0473b3f3384cea8ca0b6448845e01c
I20260812 06:19:19.212930 25048 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:19:19.220633 25048 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:19:19.221186 25048 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:19:19.232442 25048 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977: Generated new TSK 0
I20260812 06:19:19.232630 25048 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:19:19.243665 24618 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:19.245952 25071 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:19.246037 24618 server_base.cc:1061] running on GCE node
W20260812 06:19:19.245958 25068 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:19.245978 25069 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:19.246441 24618 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:19.246490 24618 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:19.246505 24618 hybrid_clock.cc:648] HybridClock initialized: now 1786515559246505 us; error 0 us; skew 500 ppm
I20260812 06:19:19.247364 24618 webserver.cc:533] Webserver started at http://127.24.10.129:45291/ using document root <none> and password file <none>
I20260812 06:19:19.247619 24618 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:19.247670 24618 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:19.247762 24618 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:19.248189 24618 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/instance:
uuid: "96338663f12c4f80a96c75743a8e2195"
format_stamp: "Formatted at 2026-08-12 06:19:19 on dist-test-slave-x4qh"
I20260812 06:19:19.249679 24618 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.001s	sys 0.001s
I20260812 06:19:19.250613 25076 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:19.250885 24618 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:19.250976 24618 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root
uuid: "96338663f12c4f80a96c75743a8e2195"
format_stamp: "Formatted at 2026-08-12 06:19:19 on dist-test-slave-x4qh"
I20260812 06:19:19.251062 24618 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-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:19.263345 24618 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:19.263834 24618 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:19.264161 24618 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:19:19.264665 24618 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:19:19.264730 24618 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:19.264796 24618 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:19:19.264853 24618 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:19.269270 24618 rpc_server.cc:307] RPC server started. Bound to: 127.24.10.129:35351
I20260812 06:19:19.270409 25188 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.24.10.129:35351 every 8 connection(s)
I20260812 06:19:19.278352 25189 heartbeater.cc:344] Connected to a master server at 127.24.10.190:41991
I20260812 06:19:19.278478 25189 heartbeater.cc:461] Registering TS with master...
I20260812 06:19:19.278707 25189 heartbeater.cc:507] Master 127.24.10.190:41991 requested a full tablet report, sending...
I20260812 06:19:19.279506 24976 ts_manager.cc:194] Registered new tserver with Master: 96338663f12c4f80a96c75743a8e2195 (127.24.10.129:35351)
I20260812 06:19:19.280189 24618 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.010025732s
I20260812 06:19:19.280421 24976 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:60700
I20260812 06:19:19.287510 24976 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:60714:
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:19.296681 25125 tablet_service.cc:1511] Processing CreateTablet for tablet 7a760506688d4fc3a75e54daf0724cb9 (DEFAULT_TABLE table=heavy-update-compaction-test [id=1e49c920d6a2495a863304c40ebdfdfb]), partition=
I20260812 06:19:19.297008 25125 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 7a760506688d4fc3a75e54daf0724cb9. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:19.298970 25212 tablet_bootstrap.cc:492] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Bootstrap starting.
I20260812 06:19:19.299938 25212 tablet_bootstrap.cc:654] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:19.301147 25212 tablet_bootstrap.cc:492] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: No bootstrap required, opened a new log
I20260812 06:19:19.301232 25212 ts_tablet_manager.cc:1403] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:19:19.301637 25212 raft_consensus.cc:359] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "96338663f12c4f80a96c75743a8e2195" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 35351 } }
I20260812 06:19:19.301728 25212 raft_consensus.cc:385] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:19.301751 25212 raft_consensus.cc:740] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 96338663f12c4f80a96c75743a8e2195, State: Initialized, Role: FOLLOWER
I20260812 06:19:19.301895 25212 consensus_queue.cc:260] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [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: "96338663f12c4f80a96c75743a8e2195" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 35351 } }
I20260812 06:19:19.302016 25212 raft_consensus.cc:399] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:19.302054 25212 raft_consensus.cc:493] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:19.302119 25212 raft_consensus.cc:3060] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:19.302985 25212 raft_consensus.cc:515] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "96338663f12c4f80a96c75743a8e2195" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 35351 } }
I20260812 06:19:19.303146 25212 leader_election.cc:304] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [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: 96338663f12c4f80a96c75743a8e2195; no voters: 
I20260812 06:19:19.303407 25212 leader_election.cc:290] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:19.303527 25214 raft_consensus.cc:2804] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:19.303773 25189 heartbeater.cc:499] Master 127.24.10.190:41991 was elected leader, sending a full tablet report...
I20260812 06:19:19.303884 25214 raft_consensus.cc:697] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 1 LEADER]: Becoming Leader. State: Replica: 96338663f12c4f80a96c75743a8e2195, State: Running, Role: LEADER
I20260812 06:19:19.304049 25214 consensus_queue.cc:237] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [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: "96338663f12c4f80a96c75743a8e2195" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 35351 } }
I20260812 06:19:19.304095 25212 ts_tablet_manager.cc:1434] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:19:19.305550 24976 catalog_manager.cc:5719] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 reported cstate change: term changed from 0 to 1, leader changed from <none> to 96338663f12c4f80a96c75743a8e2195 (127.24.10.129). New cstate: current_term: 1 leader_uuid: "96338663f12c4f80a96c75743a8e2195" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "96338663f12c4f80a96c75743a8e2195" member_type: VOTER last_known_addr { host: "127.24.10.129" port: 35351 } health_report { overall_health: HEALTHY } } }
I20260812 06:19:19.368510 24618 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.055s	user 0.013s	sys 0.012s
I20260812 06:19:19.520784 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9): perf score=19.054940
I20260812 06:19:19.678349 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.157s	user 0.119s	sys 0.036s Metrics: {"bytes_written":12307492,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":47,"dirs.run_cpu_time_us":216,"dirs.run_wall_time_us":856,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":38519,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"update_count":1500}
I20260812 06:19:19.679265 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling LogGCOp(7a760506688d4fc3a75e54daf0724cb9): free 20290830 bytes of WAL
I20260812 06:19:19.679555 25082 log_reader.cc:385] T 7a760506688d4fc3a75e54daf0724cb9: removed 2 log segments from log reader
I20260812 06:19:19.679620 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000001 (ops 1-6)
I20260812 06:19:19.679674 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000002 (ops 7-10)
I20260812 06:19:19.684819 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: LogGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.005s	user 0.000s	sys 0.004s Metrics: {}
I20260812 06:19:19.685175 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=3.181125
I20260812 06:19:19.707755 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.022s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4512899,"delete_count":0,"lbm_write_time_us":5510,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:19:19.708227 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9): 16411395 bytes on disk
I20260812 06:19:19.708736 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9) 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:19:19.709205 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:19.719110 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3787,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:19.719583 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:19.908681 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.189s	user 0.132s	sys 0.052s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774796,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":588,"lbm_read_time_us":11967,"lbm_reads_lt_1ms":569,"lbm_write_time_us":31709,"lbm_writes_lt_1ms":543,"mutex_wait_us":41,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":15360,"thread_start_us":333,"threads_started":5,"update_count":2500}
I20260812 06:19:19.909294 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:19.963984 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.054s	user 0.031s	sys 0.016s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":22045,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:19.964468 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:20.109360 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.145s	user 0.095s	sys 0.044s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672158,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":916,"lbm_read_time_us":10024,"lbm_reads_lt_1ms":463,"lbm_write_time_us":22398,"lbm_writes_lt_1ms":443,"mutex_wait_us":114,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":6784,"update_count":2000}
I20260812 06:19:20.110023 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:20.166159 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.056s	user 0.036s	sys 0.015s Metrics: {"bytes_written":16409899,"delete_count":0,"lbm_write_time_us":24305,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:20.166680 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:20.180120 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.013s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4913,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.180573 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:20.390784 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.210s	user 0.146s	sys 0.056s 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":184,"lbm_read_time_us":15008,"lbm_reads_lt_1ms":572,"lbm_write_time_us":34026,"lbm_writes_lt_1ms":543,"mutex_wait_us":37,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":9856,"update_count":2500}
I20260812 06:19:20.391527 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:20.446772 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.055s	user 0.026s	sys 0.021s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":22733,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:20.447270 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:20.459709 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.012s	user 0.007s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4564,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.460222 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:20.626139 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.166s	user 0.141s	sys 0.020s 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":499,"lbm_read_time_us":11084,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30331,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":5504,"update_count":2500}
I20260812 06:19:20.626796 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:20.679119 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.052s	user 0.028s	sys 0.020s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":22910,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":2000}
I20260812 06:19:20.679721 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:20.696317 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.016s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6084,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.696869 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:20.848439 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.151s	user 0.103s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":725,"lbm_read_time_us":9560,"lbm_reads_lt_1ms":568,"lbm_write_time_us":28869,"lbm_writes_lt_1ms":543,"mutex_wait_us":2,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2500}
I20260812 06:19:20.849260 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:20.903877 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.054s	user 0.035s	sys 0.019s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":24769,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:20.904554 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:20.923741 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.019s	user 0.005s	sys 0.012s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7406,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.924225 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:20.958220 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.034s	user 0.032s	sys 0.000s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":66,"dirs.run_cpu_time_us":217,"dirs.run_wall_time_us":1304,"drs_written":1,"lbm_read_time_us":49,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1775,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:19:20.958927 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling LogGCOp(7a760506688d4fc3a75e54daf0724cb9): free 120553374 bytes of WAL
I20260812 06:19:20.959190 25082 log_reader.cc:385] T 7a760506688d4fc3a75e54daf0724cb9: removed 12 log segments from log reader
I20260812 06:19:20.959244 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000003 (ops 11-15)
I20260812 06:19:20.959277 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000004 (ops 16-20)
I20260812 06:19:20.959352 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000005 (ops 21-24)
I20260812 06:19:20.959484 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000006 (ops 25-29)
I20260812 06:19:20.959532 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000007 (ops 30-34)
I20260812 06:19:20.959568 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000008 (ops 35-38)
I20260812 06:19:20.959640 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000009 (ops 39-43)
I20260812 06:19:20.959685 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000010 (ops 44-48)
I20260812 06:19:20.959735 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000011 (ops 49-53)
I20260812 06:19:20.959784 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000012 (ops 54-58)
I20260812 06:19:20.959829 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000013 (ops 59-63)
I20260812 06:19:20.959865 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000014 (ops 64-68)
I20260812 06:19:20.989867 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: LogGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.031s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:19:20.990334 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=6.157687
I20260812 06:19:21.021947 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.031s	user 0.015s	sys 0.014s Metrics: {"bytes_written":8205079,"delete_count":0,"lbm_write_time_us":13473,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:19:21.022511 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9): 473 bytes on disk
I20260812 06:19:21.022953 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4}
I20260812 06:19:21.023471 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:21.265919 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.242s	user 0.138s	sys 0.100s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979637,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":212,"lbm_read_time_us":15680,"lbm_reads_lt_1ms":769,"lbm_write_time_us":40476,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":67328,"thread_start_us":81,"threads_started":1,"update_count":3500}
I20260812 06:19:21.266697 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=18.063937
I20260812 06:19:21.338845 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.072s	user 0.045s	sys 0.023s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":33011,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:21.339628 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:21.360387 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.021s	user 0.014s	sys 0.002s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6608,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:21.360944 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:21.586192 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.225s	user 0.139s	sys 0.085s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877103,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":678,"lbm_read_time_us":16294,"lbm_reads_lt_1ms":664,"lbm_write_time_us":38142,"lbm_writes_lt_1ms":643,"mutex_wait_us":377,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":47744,"update_count":3000}
I20260812 06:19:21.586977 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=18.063937
I20260812 06:19:21.645884 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.059s	user 0.040s	sys 0.016s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":27258,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:21.646478 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:21.664105 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.017s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5144,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:21.664594 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:21.900017 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.235s	user 0.138s	sys 0.084s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877102,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":734,"lbm_read_time_us":13300,"lbm_reads_lt_1ms":664,"lbm_write_time_us":41451,"lbm_writes_lt_1ms":643,"mutex_wait_us":82,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":13056,"update_count":3000}
I20260812 06:19:21.900888 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=18.063937
I20260812 06:19:21.969924 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.069s	user 0.045s	sys 0.012s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":27093,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:21.970392 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:21.982097 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.012s	user 0.001s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3929,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:21.982662 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:22.198761 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.216s	user 0.132s	sys 0.084s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877104,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":820,"lbm_read_time_us":15125,"lbm_reads_lt_1ms":672,"lbm_write_time_us":35751,"lbm_writes_lt_1ms":643,"mutex_wait_us":49,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2688,"update_count":3000}
I20260812 06:19:22.199468 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=15.087375
I20260812 06:19:22.253657 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.054s	user 0.036s	sys 0.013s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":22768,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:19:22.254247 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:22.265390 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4419,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:22.265930 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:22.275892 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.010s	user 0.006s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3725,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:22.276393 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:22.481485 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.205s	user 0.120s	sys 0.079s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877206,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":280,"lbm_read_time_us":13310,"lbm_reads_lt_1ms":673,"lbm_write_time_us":33255,"lbm_writes_lt_1ms":643,"mutex_wait_us":65,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":3000}
I20260812 06:19:22.482471 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=16.079562
I20260812 06:19:22.540011 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.057s	user 0.039s	sys 0.016s Metrics: {"bytes_written":17681651,"delete_count":0,"lbm_write_time_us":25021,"lbm_writes_lt_1ms":434,"reinsert_count":0,"update_count":2155}
I20260812 06:19:22.540622 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:22.551587 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3241134,"delete_count":0,"lbm_write_time_us":3282,"lbm_writes_lt_1ms":82,"reinsert_count":0,"update_count":395}
I20260812 06:19:22.552063 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:22.565047 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.013s	user 0.011s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5076,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:22.565506 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:22.598796 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.033s	user 0.029s	sys 0.000s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":71,"dirs.run_cpu_time_us":237,"dirs.run_wall_time_us":1406,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1639,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:19:22.599575 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling LogGCOp(7a760506688d4fc3a75e54daf0724cb9): free 129773580 bytes of WAL
I20260812 06:19:22.599812 25082 log_reader.cc:385] T 7a760506688d4fc3a75e54daf0724cb9: removed 13 log segments from log reader
I20260812 06:19:22.599856 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000015 (ops 69-73)
I20260812 06:19:22.599924 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000016 (ops 74-78)
I20260812 06:19:22.599972 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000017 (ops 79-83)
I20260812 06:19:22.600034 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000018 (ops 84-88)
I20260812 06:19:22.600080 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000019 (ops 89-93)
I20260812 06:19:22.600119 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000020 (ops 94-98)
I20260812 06:19:22.600163 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000021 (ops 99-103)
I20260812 06:19:22.600206 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000022 (ops 104-108)
I20260812 06:19:22.600246 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000023 (ops 109-112)
I20260812 06:19:22.600286 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000024 (ops 113-117)
I20260812 06:19:22.600325 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000025 (ops 118-122)
I20260812 06:19:22.600364 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000026 (ops 123-127)
I20260812 06:19:22.600404 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000027 (ops 128-132)
I20260812 06:19:22.630908 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: LogGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.031s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:19:22.631294 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9): 482 bytes on disk
I20260812 06:19:22.632575 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4}
I20260812 06:19:22.633292 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=4.173312
I20260812 06:19:22.647980 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.015s	user 0.010s	sys 0.004s Metrics: {"bytes_written":5743633,"delete_count":0,"lbm_write_time_us":6074,"lbm_writes_lt_1ms":143,"reinsert_count":0,"update_count":700}
I20260812 06:19:22.648494 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.196750
I20260812 06:19:22.658344 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.010s	user 0.006s	sys 0.000s Metrics: {"bytes_written":2461654,"delete_count":0,"lbm_write_time_us":2521,"lbm_writes_lt_1ms":63,"reinsert_count":0,"update_count":300}
I20260812 06:19:22.658874 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:22.917023 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.258s	user 0.179s	sys 0.079s Metrics: {"cfile_cache_miss":835,"cfile_cache_miss_bytes":37082215,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":474,"lbm_read_time_us":19421,"lbm_reads_lt_1ms":867,"lbm_write_time_us":50542,"lbm_writes_lt_1ms":843,"mutex_wait_us":44,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":16512,"thread_start_us":86,"threads_started":1,"update_count":4000}
I20260812 06:19:22.917855 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=19.056125
I20260812 06:19:22.988584 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.070s	user 0.040s	sys 0.027s Metrics: {"bytes_written":20922552,"delete_count":0,"lbm_write_time_us":29693,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":512,"reinsert_count":0,"update_count":2550}
I20260812 06:19:22.989136 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=6.157687
I20260812 06:19:23.024494 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.035s	user 0.015s	sys 0.008s Metrics: {"bytes_written":7794837,"delete_count":0,"lbm_write_time_us":10347,"lbm_writes_lt_1ms":193,"mutex_wait_us":51,"reinsert_count":0,"update_count":950}
I20260812 06:19:23.024997 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:23.035218 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3898,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:23.035943 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:23.250106 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.214s	user 0.170s	sys 0.042s Metrics: {"cfile_cache_miss":833,"cfile_cache_miss_bytes":37082042,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":211,"lbm_read_time_us":16240,"lbm_reads_lt_1ms":873,"lbm_write_time_us":46459,"lbm_writes_lt_1ms":843,"mutex_wait_us":76,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":7808,"update_count":4000}
I20260812 06:19:23.250690 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=18.063937
I20260812 06:19:23.309695 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.059s	user 0.032s	sys 0.024s Metrics: {"bytes_written":20512318,"delete_count":0,"lbm_write_time_us":26788,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:23.310323 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:23.326449 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.016s	user 0.011s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6456,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:23.326960 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:23.494380 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.167s	user 0.135s	sys 0.032s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877105,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":993,"lbm_read_time_us":10838,"lbm_reads_lt_1ms":672,"lbm_write_time_us":36144,"lbm_writes_lt_1ms":643,"mutex_wait_us":265,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2688,"update_count":3000}
I20260812 06:19:23.495074 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:23.542761 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.048s	user 0.031s	sys 0.012s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":21313,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:19:23.543473 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:23.557772 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.014s	user 0.010s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5026,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:23.558274 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:23.717162 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.159s	user 0.106s	sys 0.043s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774689,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":513,"lbm_read_time_us":9450,"lbm_reads_lt_1ms":568,"lbm_write_time_us":30458,"lbm_writes_lt_1ms":543,"mutex_wait_us":59,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":35072,"update_count":2500}
I20260812 06:19:23.717903 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:23.786291 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.068s	user 0.037s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":25617,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:23.786861 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:23.798921 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.012s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4328,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:23.799520 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:23.974768 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.175s	user 0.130s	sys 0.045s 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":222,"lbm_read_time_us":12418,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33322,"lbm_writes_lt_1ms":543,"mutex_wait_us":33,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6272,"update_count":2500}
I20260812 06:19:23.975641 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=14.095187
I20260812 06:19:24.032315 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.056s	user 0.039s	sys 0.016s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":25564,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:24.032943 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=2.188937
I20260812 06:19:24.050880 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.018s	user 0.012s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7005,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:24.051563 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:24.085391 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushMRSOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.034s	user 0.030s	sys 0.001s Metrics: {"bytes_written":1316417,"cfile_init":1,"dirs.queue_time_us":74,"dirs.run_cpu_time_us":203,"dirs.run_wall_time_us":1375,"drs_written":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2011,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:19:24.086144 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling LogGCOp(7a760506688d4fc3a75e54daf0724cb9): free 124257456 bytes of WAL
I20260812 06:19:24.086432 25082 log_reader.cc:385] T 7a760506688d4fc3a75e54daf0724cb9: removed 12 log segments from log reader
I20260812 06:19:24.086508 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000028 (ops 133-136)
I20260812 06:19:24.086598 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000029 (ops 137-141)
I20260812 06:19:24.086655 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000030 (ops 142-146)
I20260812 06:19:24.086699 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000031 (ops 147-151)
I20260812 06:19:24.086745 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000032 (ops 152-156)
I20260812 06:19:24.086793 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000033 (ops 157-161)
I20260812 06:19:24.086841 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000034 (ops 162-166)
I20260812 06:19:24.086889 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000035 (ops 167-171)
I20260812 06:19:24.086938 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000036 (ops 172-176)
I20260812 06:19:24.086987 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000037 (ops 177-181)
I20260812 06:19:24.087034 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000038 (ops 182-186)
I20260812 06:19:24.087085 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000039 (ops 187-191)
I20260812 06:19:24.115029 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: LogGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.029s	user 0.000s	sys 0.025s Metrics: {}
I20260812 06:19:24.115483 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9): 493 bytes on disk
I20260812 06:19:24.115895 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: UndoDeltaBlockGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":51,"lbm_reads_lt_1ms":4}
I20260812 06:19:24.116386 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9): perf score=6.157687
I20260812 06:19:24.143654 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: FlushDeltaMemStoresOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.027s	user 0.015s	sys 0.009s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10652,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:19:24.144188 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling LogGCOp(7a760506688d4fc3a75e54daf0724cb9): free 8767197 bytes of WAL
I20260812 06:19:24.144416 25082 log_reader.cc:385] T 7a760506688d4fc3a75e54daf0724cb9: removed 1 log segments from log reader
I20260812 06:19:24.144464 25082 log.cc:1079] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: Deleting log segment in path: /tmp/dist-test-taskMMpk6l/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515553834549-24618-0/minicluster-data/ts-0-root/wals/7a760506688d4fc3a75e54daf0724cb9/wal-000000040 (ops 192-196)
I20260812 06:19:24.146239 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: LogGCOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.002s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:19:24.146550 25190 maintenance_manager.cc:419] P 96338663f12c4f80a96c75743a8e2195: Scheduling MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9): perf score=1.000000
I20260812 06:19:24.200474 24618 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.832s	user 1.783s	sys 0.160s
I20260812 06:19:24.292001 24618 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.091s	user 0.002s	sys 0.000s
I20260812 06:19:24.292539 24618 tablet_server.cc:179] TabletServer@127.24.10.129:0 shutting down...
I20260812 06:19:24.357684 25082 maintenance_manager.cc:643] P 96338663f12c4f80a96c75743a8e2195: MajorDeltaCompactionOp(7a760506688d4fc3a75e54daf0724cb9) complete. Timing: real 0.211s	user 0.131s	sys 0.079s Metrics: {"cfile_cache_miss":733,"cfile_cache_miss_bytes":32979635,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":695,"lbm_read_time_us":14310,"lbm_reads_lt_1ms":761,"lbm_write_time_us":37953,"lbm_writes_lt_1ms":743,"mutex_wait_us":104,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":17792,"thread_start_us":94,"threads_started":1,"update_count":3500}
I20260812 06:19:24.358443 24618 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:19:24.358920 24618 tablet_replica.cc:333] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195: stopping tablet replica
I20260812 06:19:24.359107 24618 raft_consensus.cc:2243] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:24.359334 24618 raft_consensus.cc:2272] T 7a760506688d4fc3a75e54daf0724cb9 P 96338663f12c4f80a96c75743a8e2195 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:24.376714 24618 tablet_server.cc:196] TabletServer@127.24.10.129:0 shutdown complete.
I20260812 06:19:24.415897 24618 master.cc:562] Master@127.24.10.190:41991 shutting down...
I20260812 06:19:24.419672 24618 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:24.419848 24618 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:24.419901 24618 tablet_replica.cc:333] T 00000000000000000000000000000000 P 063ffa7e35054c26ad9114f669c8e977: stopping tablet replica
I20260812 06:19:24.432195 24618 master.cc:584] Master@127.24.10.190:41991 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5364 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10676 ms total)

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