[==========] 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:16:54.042062   873 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.0.218.126:43973
I20260812 06:16:54.043289   873 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:16:54.043957   873 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:16:54.050952   884 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:16:54.051038   880 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:16:54.051261   878 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:16:54.051371   873 server_base.cc:1061] running on GCE node
I20260812 06:16:54.051970   873 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:16:54.052067   873 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:16:54.052129   873 hybrid_clock.cc:648] HybridClock initialized: now 1786515414052125 us; error 0 us; skew 500 ppm
I20260812 06:16:54.054209   873 webserver.cc:533] Webserver started at http://127.0.218.126:37353/ using document root <none> and password file <none>
I20260812 06:16:54.054894   873 fs_manager.cc:362] Metadata directory not provided
I20260812 06:16:54.054975   873 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:16:54.055234   873 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:16:54.057000   873 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/master-0-root/instance:
uuid: "5b445f0dde7d45e3bccae1a52dbd59e5"
format_stamp: "Formatted at 2026-08-12 06:16:54 on dist-test-slave-8tdl"
I20260812 06:16:54.061257   873 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.005s	sys 0.000s
I20260812 06:16:54.063921   889 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:16:54.065478   873 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:16:54.065663   873 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/master-0-root
uuid: "5b445f0dde7d45e3bccae1a52dbd59e5"
format_stamp: "Formatted at 2026-08-12 06:16:54 on dist-test-slave-8tdl"
I20260812 06:16:54.065800   873 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-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:16:54.076313   873 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:16:54.077032   873 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:16:54.077229   873 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:16:54.085724   873 rpc_server.cc:307] RPC server started. Bound to: 127.0.218.126:43973
I20260812 06:16:54.085794   950 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.218.126:43973 every 8 connection(s)
I20260812 06:16:54.088173   951 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:16:54.093930   951 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5: Bootstrap starting.
I20260812 06:16:54.096580   951 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5: Neither blocks nor log segments found. Creating new log.
I20260812 06:16:54.097573   951 log.cc:826] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5: Log is configured to *not* fsync() on all Append() calls
I20260812 06:16:54.099538   951 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5: No bootstrap required, opened a new log
I20260812 06:16:54.102523   951 raft_consensus.cc:359] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5b445f0dde7d45e3bccae1a52dbd59e5" member_type: VOTER }
I20260812 06:16:54.102782   951 raft_consensus.cc:385] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:16:54.102896   951 raft_consensus.cc:740] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5b445f0dde7d45e3bccae1a52dbd59e5, State: Initialized, Role: FOLLOWER
I20260812 06:16:54.103557   951 consensus_queue.cc:260] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [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: "5b445f0dde7d45e3bccae1a52dbd59e5" member_type: VOTER }
I20260812 06:16:54.103727   951 raft_consensus.cc:399] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:16:54.103808   951 raft_consensus.cc:493] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:16:54.103933   951 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:16:54.104787   951 raft_consensus.cc:515] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5b445f0dde7d45e3bccae1a52dbd59e5" member_type: VOTER }
I20260812 06:16:54.105263   951 leader_election.cc:304] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [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: 5b445f0dde7d45e3bccae1a52dbd59e5; no voters: 
I20260812 06:16:54.105639   951 leader_election.cc:290] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:16:54.105831   954 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:16:54.106115   954 raft_consensus.cc:697] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 1 LEADER]: Becoming Leader. State: Replica: 5b445f0dde7d45e3bccae1a52dbd59e5, State: Running, Role: LEADER
I20260812 06:16:54.106513   954 consensus_queue.cc:237] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [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: "5b445f0dde7d45e3bccae1a52dbd59e5" member_type: VOTER }
I20260812 06:16:54.106750   951 sys_catalog.cc:565] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:16:54.109076   956 sys_catalog.cc:455] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 5b445f0dde7d45e3bccae1a52dbd59e5. Latest consensus state: current_term: 1 leader_uuid: "5b445f0dde7d45e3bccae1a52dbd59e5" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5b445f0dde7d45e3bccae1a52dbd59e5" member_type: VOTER } }
I20260812 06:16:54.109110   955 sys_catalog.cc:455] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "5b445f0dde7d45e3bccae1a52dbd59e5" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5b445f0dde7d45e3bccae1a52dbd59e5" member_type: VOTER } }
I20260812 06:16:54.109211   956 sys_catalog.cc:458] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [sys.catalog]: This master's current role is: LEADER
I20260812 06:16:54.109210   955 sys_catalog.cc:458] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [sys.catalog]: This master's current role is: LEADER
I20260812 06:16:54.109241   873 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
W20260812 06:16:54.111624   972 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:16:54.111696   972 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:16:54.111775   973 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:16:54.112499   973 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:16:54.117727   973 catalog_manager.cc:1383] Generated new cluster ID: 3427f954a78c47668525c7c1af9fd133
I20260812 06:16:54.117833   973 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:16:54.135648   973 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:16:54.136659   973 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:16:54.147065   973 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5: Generated new TSK 0
I20260812 06:16:54.147799   973 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:16:54.174366   873 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:16:54.177842   981 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:16:54.177836   979 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:16:54.177836   978 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:16:54.178200   873 server_base.cc:1061] running on GCE node
I20260812 06:16:54.178532   873 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:16:54.178592   873 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:16:54.178615   873 hybrid_clock.cc:648] HybridClock initialized: now 1786515414178615 us; error 0 us; skew 500 ppm
I20260812 06:16:54.179688   873 webserver.cc:533] Webserver started at http://127.0.218.65:33361/ using document root <none> and password file <none>
I20260812 06:16:54.179862   873 fs_manager.cc:362] Metadata directory not provided
I20260812 06:16:54.179924   873 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:16:54.180007   873 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:16:54.180471   873 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/instance:
uuid: "5962154493774f2abb0025928a4f0f69"
format_stamp: "Formatted at 2026-08-12 06:16:54 on dist-test-slave-8tdl"
I20260812 06:16:54.182394   873 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:16:54.183622   986 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:16:54.183945   873 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:16:54.184022   873 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root
uuid: "5962154493774f2abb0025928a4f0f69"
format_stamp: "Formatted at 2026-08-12 06:16:54 on dist-test-slave-8tdl"
I20260812 06:16:54.184118   873 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-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:16:54.205256   873 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:16:54.205798   873 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:16:54.206374   873 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:16:54.207378   873 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:16:54.207435   873 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:16:54.207513   873 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:16:54.207553   873 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:16:54.214461   873 rpc_server.cc:307] RPC server started. Bound to: 127.0.218.65:42633
I20260812 06:16:54.214574  1061 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.218.65:42633 every 8 connection(s)
I20260812 06:16:54.229898  1062 heartbeater.cc:344] Connected to a master server at 127.0.218.126:43973
I20260812 06:16:54.230201  1062 heartbeater.cc:461] Registering TS with master...
I20260812 06:16:54.230899  1062 heartbeater.cc:507] Master 127.0.218.126:43973 requested a full tablet report, sending...
I20260812 06:16:54.232542   911 ts_manager.cc:194] Registered new tserver with Master: 5962154493774f2abb0025928a4f0f69 (127.0.218.65:42633)
I20260812 06:16:54.233392   873 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.018176754s
I20260812 06:16:54.233992   911 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:40702
I20260812 06:16:54.244167   911 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:40716:
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:16:54.259721  1018 tablet_service.cc:1511] Processing CreateTablet for tablet 6fa7dea79d454d249533bbd7d5da8a48 (DEFAULT_TABLE table=heavy-update-compaction-test [id=dc22bd42a5c546c1b19e4fdd7eac68cd]), partition=
I20260812 06:16:54.260264  1018 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 6fa7dea79d454d249533bbd7d5da8a48. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:16:54.262562  1077 tablet_bootstrap.cc:492] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Bootstrap starting.
I20260812 06:16:54.264117  1077 tablet_bootstrap.cc:654] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Neither blocks nor log segments found. Creating new log.
I20260812 06:16:54.265569  1077 tablet_bootstrap.cc:492] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: No bootstrap required, opened a new log
I20260812 06:16:54.265708  1077 ts_tablet_manager.cc:1403] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Time spent bootstrapping tablet: real 0.003s	user 0.000s	sys 0.002s
I20260812 06:16:54.266237  1077 raft_consensus.cc:359] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5962154493774f2abb0025928a4f0f69" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 42633 } }
I20260812 06:16:54.266345  1077 raft_consensus.cc:385] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:16:54.266371  1077 raft_consensus.cc:740] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5962154493774f2abb0025928a4f0f69, State: Initialized, Role: FOLLOWER
I20260812 06:16:54.266541  1077 consensus_queue.cc:260] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [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: "5962154493774f2abb0025928a4f0f69" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 42633 } }
I20260812 06:16:54.266661  1077 raft_consensus.cc:399] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:16:54.266794  1077 raft_consensus.cc:493] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:16:54.266888  1077 raft_consensus.cc:3060] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:16:54.267881  1077 raft_consensus.cc:515] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5962154493774f2abb0025928a4f0f69" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 42633 } }
I20260812 06:16:54.268038  1077 leader_election.cc:304] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [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: 5962154493774f2abb0025928a4f0f69; no voters: 
I20260812 06:16:54.268306  1077 leader_election.cc:290] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:16:54.268420  1079 raft_consensus.cc:2804] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:16:54.268687  1077 ts_tablet_manager.cc:1434] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:16:54.268915  1062 heartbeater.cc:499] Master 127.0.218.126:43973 was elected leader, sending a full tablet report...
I20260812 06:16:54.268714  1079 raft_consensus.cc:697] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 1 LEADER]: Becoming Leader. State: Replica: 5962154493774f2abb0025928a4f0f69, State: Running, Role: LEADER
I20260812 06:16:54.269351  1079 consensus_queue.cc:237] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [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: "5962154493774f2abb0025928a4f0f69" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 42633 } }
I20260812 06:16:54.272464   911 catalog_manager.cc:5719] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 reported cstate change: term changed from 0 to 1, leader changed from <none> to 5962154493774f2abb0025928a4f0f69 (127.0.218.65). New cstate: current_term: 1 leader_uuid: "5962154493774f2abb0025928a4f0f69" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5962154493774f2abb0025928a4f0f69" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 42633 } health_report { overall_health: HEALTHY } } }
I20260812 06:16:54.338763   873 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.058s	user 0.021s	sys 0.005s
I20260812 06:16:54.465982  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=15.086190
I20260812 06:16:54.626359   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.160s	user 0.129s	sys 0.020s Metrics: {"bytes_written":12307490,"cfile_init":1,"compiler_manager_pool.queue_time_us":377,"delete_count":0,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":214,"dirs.run_wall_time_us":1223,"drs_written":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4,"lbm_write_time_us":37447,"lbm_writes_lt_1ms":657,"peak_mem_usage":0,"reinsert_count":0,"rows_written":103,"thread_start_us":230,"threads_started":1,"update_count":1500}
I20260812 06:16:54.627782  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling LogGCOp(6fa7dea79d454d249533bbd7d5da8a48): free 20290830 bytes of WAL
I20260812 06:16:54.628129   993 log_reader.cc:385] T 6fa7dea79d454d249533bbd7d5da8a48: removed 2 log segments from log reader
I20260812 06:16:54.628198   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000001 (ops 1-6)
I20260812 06:16:54.628262   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000002 (ops 7-10)
I20260812 06:16:54.633950   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: LogGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.006s	user 0.000s	sys 0.003s Metrics: {}
I20260812 06:16:54.634533  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:54.655059   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.020s	user 0.017s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6743,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:54.655686  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:54.794891   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.139s	user 0.110s	sys 0.028s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1308,"lbm_read_time_us":9008,"lbm_reads_lt_1ms":464,"lbm_write_time_us":26244,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"thread_start_us":451,"threads_started":5,"update_count":2000}
I20260812 06:16:54.795583  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48): 12308959 bytes on disk
I20260812 06:16:54.796224   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":69,"lbm_reads_lt_1ms":4}
I20260812 06:16:54.796870  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:54.834124   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.037s	user 0.013s	sys 0.018s Metrics: {"bytes_written":12307557,"delete_count":0,"lbm_write_time_us":14573,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:16:54.834800  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:54.846585   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4001,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:54.847133  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:54.980459   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.133s	user 0.107s	sys 0.023s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631379,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":655,"lbm_read_time_us":9954,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24522,"lbm_writes_lt_1ms":443,"mutex_wait_us":120,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":73728,"update_count":2000}
I20260812 06:16:54.981051  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:55.027467   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.046s	user 0.014s	sys 0.020s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":15284,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:16:55.027932  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:55.039386   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4144,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:55.040010  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:55.175370   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.135s	user 0.091s	sys 0.044s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631310,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":166,"lbm_read_time_us":9730,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26751,"lbm_writes_lt_1ms":443,"mutex_wait_us":46,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":4864,"update_count":2000}
I20260812 06:16:55.176002  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:55.227264   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.051s	user 0.019s	sys 0.027s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":20032,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:16:55.227910  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:55.240468   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.012s	user 0.002s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4927,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:55.241056  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:55.408408   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.167s	user 0.149s	sys 0.018s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":635,"lbm_read_time_us":12150,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28490,"lbm_writes_lt_1ms":443,"mutex_wait_us":285,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":72576,"update_count":2000}
I20260812 06:16:55.408926  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:55.457419   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.048s	user 0.025s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17272,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:16:55.457984  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:55.470149   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4280,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:55.470656  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:55.599303   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.128s	user 0.124s	sys 0.005s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631312,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":210,"lbm_read_time_us":9255,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25565,"lbm_writes_lt_1ms":443,"mutex_wait_us":23,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2000}
I20260812 06:16:55.599947  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:55.642128   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.042s	user 0.022s	sys 0.012s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":16248,"lbm_writes_lt_1ms":303,"mutex_wait_us":491,"reinsert_count":0,"update_count":1500}
I20260812 06:16:55.642830  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:55.659763   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.016s	user 0.009s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6009,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:55.660279  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:55.819933   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.159s	user 0.126s	sys 0.033s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631311,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":588,"lbm_read_time_us":11475,"lbm_reads_lt_1ms":472,"lbm_write_time_us":31500,"lbm_writes_lt_1ms":443,"mutex_wait_us":358,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":17280,"update_count":2000}
I20260812 06:16:55.820660  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:55.867152   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.046s	user 0.019s	sys 0.020s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16808,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:16:55.867779  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:55.880815   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.013s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4817,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:55.881405  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:55.912662   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.031s	user 0.029s	sys 0.000s Metrics: {"bytes_written":1152509,"cfile_init":1,"dirs.queue_time_us":54,"dirs.run_cpu_time_us":236,"dirs.run_wall_time_us":1920,"drs_written":1,"lbm_read_time_us":48,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1774,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:16:55.913748  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling LogGCOp(6fa7dea79d454d249533bbd7d5da8a48): free 112692364 bytes of WAL
I20260812 06:16:55.914091   993 log_reader.cc:385] T 6fa7dea79d454d249533bbd7d5da8a48: removed 11 log segments from log reader
I20260812 06:16:55.914170   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000003 (ops 11-15)
I20260812 06:16:55.914234   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000004 (ops 16-20)
I20260812 06:16:55.914261   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000005 (ops 21-25)
I20260812 06:16:55.914300   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000006 (ops 26-30)
I20260812 06:16:55.914390   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000007 (ops 31-35)
I20260812 06:16:55.914453   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000008 (ops 36-40)
I20260812 06:16:55.914494   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000009 (ops 41-45)
I20260812 06:16:55.914531   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000010 (ops 46-50)
I20260812 06:16:55.914572   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000011 (ops 51-55)
I20260812 06:16:55.914608   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000012 (ops 56-60)
I20260812 06:16:55.914649   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000013 (ops 61-65)
I20260812 06:16:55.940173   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: LogGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.026s	user 0.000s	sys 0.025s Metrics: {}
I20260812 06:16:55.940663  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=3.181125
I20260812 06:16:55.954378   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.014s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4512904,"delete_count":0,"lbm_write_time_us":4826,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:16:55.954921  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48): 446 bytes on disk
I20260812 06:16:55.955359   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4}
I20260812 06:16:55.955809  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:55.965884   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3721,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:16:55.966419  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:56.156905   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.190s	user 0.144s	sys 0.039s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28836365,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1008,"lbm_read_time_us":13571,"lbm_reads_lt_1ms":674,"lbm_write_time_us":35352,"lbm_writes_lt_1ms":643,"mutex_wait_us":563,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":19200,"thread_start_us":82,"threads_started":1,"update_count":3000}
I20260812 06:16:56.157612  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=14.095187
I20260812 06:16:56.214888   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.057s	user 0.026s	sys 0.024s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":22512,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:16:56.215370  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:56.228266   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4347,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:56.229069  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:56.399895   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.171s	user 0.111s	sys 0.048s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733726,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":466,"lbm_read_time_us":10499,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30239,"lbm_writes_lt_1ms":543,"mutex_wait_us":3,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6656,"update_count":2500}
I20260812 06:16:56.400753  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=14.095187
I20260812 06:16:56.473523   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.072s	user 0.029s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":27083,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:16:56.474123  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:56.486125   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.012s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4318,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:56.486644  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:56.669270   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.182s	user 0.125s	sys 0.045s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733723,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":913,"lbm_read_time_us":11600,"lbm_reads_lt_1ms":572,"lbm_write_time_us":31841,"lbm_writes_lt_1ms":543,"mutex_wait_us":24,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13568,"update_count":2500}
I20260812 06:16:56.669986  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=14.095187
I20260812 06:16:56.726980   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.057s	user 0.035s	sys 0.019s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":24199,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:16:56.727626  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:56.738890   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.011s	user 0.003s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4491,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:56.739503  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:56.913962   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.174s	user 0.107s	sys 0.060s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733721,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":163,"lbm_read_time_us":13706,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27070,"lbm_writes_lt_1ms":543,"mutex_wait_us":285,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":16640,"update_count":2500}
I20260812 06:16:56.914834  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=14.095187
I20260812 06:16:56.973186   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.058s	user 0.036s	sys 0.020s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":22251,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:16:56.973799  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:56.984567   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4026,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:56.985239  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:57.166481   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.181s	user 0.118s	sys 0.056s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733724,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":236,"lbm_read_time_us":12029,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30113,"lbm_writes_lt_1ms":543,"mutex_wait_us":26,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":16512,"update_count":2500}
I20260812 06:16:57.167265  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=14.095187
I20260812 06:16:57.229521   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.062s	user 0.040s	sys 0.012s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24579,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:16:57.230053  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:57.251000   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.021s	user 0.013s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4274,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:57.251789  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:57.442126   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.190s	user 0.143s	sys 0.040s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733723,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":898,"lbm_read_time_us":12705,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29061,"lbm_writes_lt_1ms":543,"mutex_wait_us":395,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:16:57.442847  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=14.095187
I20260812 06:16:57.502971   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.060s	user 0.031s	sys 0.028s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":25629,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:16:57.503575  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:57.517154   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4882,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:57.517828  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:57.561896   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.044s	user 0.038s	sys 0.003s Metrics: {"bytes_written":1316419,"cfile_init":1,"dirs.queue_time_us":105,"dirs.run_cpu_time_us":286,"dirs.run_wall_time_us":1577,"drs_written":1,"lbm_read_time_us":66,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2336,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:16:57.562865  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling LogGCOp(6fa7dea79d454d249533bbd7d5da8a48): free 124710298 bytes of WAL
I20260812 06:16:57.563154   993 log_reader.cc:385] T 6fa7dea79d454d249533bbd7d5da8a48: removed 12 log segments from log reader
I20260812 06:16:57.563222   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000014 (ops 66-70)
I20260812 06:16:57.563266   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000015 (ops 71-75)
I20260812 06:16:57.563299   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000016 (ops 76-80)
I20260812 06:16:57.563323   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000017 (ops 81-85)
I20260812 06:16:57.563354   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000018 (ops 86-90)
I20260812 06:16:57.563390   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000019 (ops 91-95)
I20260812 06:16:57.563414   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000020 (ops 96-100)
I20260812 06:16:57.563444   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000021 (ops 101-105)
I20260812 06:16:57.563474   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000022 (ops 106-110)
I20260812 06:16:57.563505   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000023 (ops 111-115)
I20260812 06:16:57.563544   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000024 (ops 116-120)
I20260812 06:16:57.563577   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000025 (ops 121-125)
I20260812 06:16:57.596004   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: LogGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.033s	user 0.000s	sys 0.032s Metrics: {}
I20260812 06:16:57.596498  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48): 492 bytes on disk
I20260812 06:16:57.596974   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48) 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:16:57.597494  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=2.188937
I20260812 06:16:57.649076   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.051s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6137,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:16:57.649838  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling LogGCOp(6fa7dea79d454d249533bbd7d5da8a48): free 12017940 bytes of WAL
I20260812 06:16:57.650151   993 log_reader.cc:385] T 6fa7dea79d454d249533bbd7d5da8a48: removed 1 log segments from log reader
I20260812 06:16:57.650251   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000026 (ops 126-130)
I20260812 06:16:57.653322   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: LogGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.003s	user 0.000s	sys 0.001s Metrics: {}
I20260812 06:16:57.653700  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=3.181125
I20260812 06:16:57.731117   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.077s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":6780,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:16:57.731635  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=6.157687
I20260812 06:16:57.837071   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.105s	user 0.011s	sys 0.013s Metrics: {"bytes_written":7794836,"delete_count":0,"lbm_write_time_us":10473,"lbm_writes_lt_1ms":193,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":950}
I20260812 06:16:57.837817  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=7.149875
I20260812 06:16:57.934370   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.096s	user 0.020s	sys 0.008s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":12534,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:16:57.935107  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=6.157687
I20260812 06:16:58.040333   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.105s	user 0.021s	sys 0.007s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":12065,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:16:58.041263  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=7.149875
I20260812 06:16:58.142431   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.101s	user 0.024s	sys 0.004s Metrics: {"bytes_written":9476830,"delete_count":0,"lbm_write_time_us":12192,"lbm_writes_lt_1ms":234,"reinsert_count":0,"update_count":1155}
I20260812 06:16:58.143203  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=5.165500
I20260812 06:16:58.242152   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.099s	user 0.008s	sys 0.012s Metrics: {"bytes_written":7261532,"delete_count":0,"lbm_write_time_us":8396,"lbm_writes_lt_1ms":180,"reinsert_count":0,"update_count":885}
I20260812 06:16:58.242961  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:58.341636   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.098s	user 0.015s	sys 0.012s Metrics: {"bytes_written":11569066,"delete_count":0,"lbm_write_time_us":12379,"lbm_writes_lt_1ms":285,"reinsert_count":0,"update_count":1410}
I20260812 06:16:58.342370  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=6.157687
I20260812 06:16:58.446360   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.104s	user 0.017s	sys 0.007s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10530,"lbm_writes_lt_1ms":203,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":1000}
I20260812 06:16:58.447219  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=7.149875
I20260812 06:16:58.553359   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.106s	user 0.014s	sys 0.008s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":9836,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:16:58.553922  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=10.126437
I20260812 06:16:58.660620   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.106s	user 0.025s	sys 0.007s Metrics: {"bytes_written":11897249,"delete_count":0,"lbm_write_time_us":14172,"lbm_writes_lt_1ms":293,"reinsert_count":0,"update_count":1450}
I20260812 06:16:58.661355  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=7.149875
I20260812 06:16:58.760182   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.099s	user 0.010s	sys 0.011s Metrics: {"bytes_written":8615322,"delete_count":0,"lbm_write_time_us":9055,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:16:58.760934  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=9.134250
I20260812 06:16:58.863224   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.102s	user 0.030s	sys 0.003s Metrics: {"bytes_written":11404961,"delete_count":0,"lbm_write_time_us":14449,"lbm_writes_lt_1ms":281,"mutex_wait_us":287,"reinsert_count":0,"update_count":1390}
I20260812 06:16:58.863992  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=7.149875
I20260812 06:16:58.959404   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.095s	user 0.019s	sys 0.012s Metrics: {"bytes_written":8697372,"delete_count":0,"lbm_write_time_us":13330,"lbm_writes_lt_1ms":215,"reinsert_count":0,"update_count":1060}
I20260812 06:16:58.960255  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=6.157687
I20260812 06:16:59.064610   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.104s	user 0.013s	sys 0.014s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":11176,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:16:59.065310  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=6.157687
I20260812 06:16:59.162992   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.097s	user 0.010s	sys 0.012s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":8950,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:16:59.163717  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=8.142062
I20260812 06:16:59.222667   873 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.884s	user 1.809s	sys 0.137s
I20260812 06:16:59.262830   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.099s	user 0.017s	sys 0.012s Metrics: {"bytes_written":10338332,"delete_count":0,"lbm_write_time_us":12910,"lbm_writes_lt_1ms":255,"reinsert_count":0,"update_count":1260}
I20260812 06:16:59.263698  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=4.173312
I20260812 06:16:59.365486   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushDeltaMemStoresOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.102s	user 0.016s	sys 0.000s Metrics: {"bytes_written":6071828,"delete_count":0,"lbm_write_time_us":6801,"lbm_writes_lt_1ms":151,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":740}
I20260812 06:16:59.366201  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:59.468958   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: FlushMRSOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.103s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1521477,"cfile_init":1,"dirs.queue_time_us":250,"drs_written":1,"lbm_read_time_us":35,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1986,"lbm_writes_lt_1ms":44,"peak_mem_usage":0,"rows_written":37,"thread_start_us":117,"threads_started":1}
I20260812 06:16:59.470077  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling LogGCOp(6fa7dea79d454d249533bbd7d5da8a48): free 129773860 bytes of WAL
I20260812 06:16:59.470402   993 log_reader.cc:385] T 6fa7dea79d454d249533bbd7d5da8a48: removed 13 log segments from log reader
I20260812 06:16:59.470458   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000027 (ops 131-135)
I20260812 06:16:59.470543   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000028 (ops 136-140)
I20260812 06:16:59.470592   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000029 (ops 141-144)
I20260812 06:16:59.470695   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000030 (ops 145-149)
I20260812 06:16:59.470746   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000031 (ops 150-154)
I20260812 06:16:59.470800   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000032 (ops 155-159)
I20260812 06:16:59.470870   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000033 (ops 160-164)
I20260812 06:16:59.470942   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000034 (ops 165-169)
I20260812 06:16:59.470997   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000035 (ops 170-174)
I20260812 06:16:59.471043   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000036 (ops 175-179)
I20260812 06:16:59.471086   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000037 (ops 180-184)
I20260812 06:16:59.471128   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000038 (ops 185-189)
I20260812 06:16:59.471171   993 log.cc:1079] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515414030787-873-0/minicluster-data/ts-0-root/wals/6fa7dea79d454d249533bbd7d5da8a48/wal-000000039 (ops 190-194)
I20260812 06:16:59.496892   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: LogGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.027s	user 0.002s	sys 0.023s Metrics: {}
I20260812 06:16:59.497401  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48): 553 bytes on disk
I20260812 06:16:59.497871   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: UndoDeltaBlockGCOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":50,"lbm_reads_lt_1ms":4}
I20260812 06:16:59.498541  1063 maintenance_manager.cc:419] P 5962154493774f2abb0025928a4f0f69: Scheduling MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48): perf score=1.000000
I20260812 06:16:59.670029   873 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.447s	user 0.003s	sys 0.000s
I20260812 06:16:59.671023   873 tablet_server.cc:179] TabletServer@127.0.218.65:0 shutting down...
I20260812 06:17:00.450016   993 maintenance_manager.cc:643] P 5962154493774f2abb0025928a4f0f69: MajorDeltaCompactionOp(6fa7dea79d454d249533bbd7d5da8a48) complete. Timing: real 0.951s	user 0.547s	sys 0.403s Metrics: {"cfile_cache_hit":3818,"cfile_cache_hit_bytes":155893853,"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631317,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":20,"delta_iterators_relevant":20,"dirs.queue_time_us":1716,"lbm_read_time_us":9282,"lbm_reads_lt_1ms":468,"lbm_write_time_us":225290,"lbm_writes_lt_1ms":4246,"peak_mem_usage":522899064,"reinsert_count":0,"spinlock_wait_cycles":793344,"thread_start_us":612,"threads_started":8,"update_count":21000,"wal-append.queue_time_us":313}
I20260812 06:17:00.451023   873 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:00.451496   873 tablet_replica.cc:333] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69: stopping tablet replica
I20260812 06:17:00.451785   873 raft_consensus.cc:2243] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:00.452188   873 raft_consensus.cc:2272] T 6fa7dea79d454d249533bbd7d5da8a48 P 5962154493774f2abb0025928a4f0f69 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:00.458307   873 tablet_server.cc:196] TabletServer@127.0.218.65:0 shutdown complete.
I20260812 06:17:01.097903   873 master.cc:562] Master@127.0.218.126:43973 shutting down...
I20260812 06:17:01.102527   873 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:01.102768   873 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:01.102836   873 tablet_replica.cc:333] T 00000000000000000000000000000000 P 5b445f0dde7d45e3bccae1a52dbd59e5: stopping tablet replica
I20260812 06:17:01.115450   873 master.cc:584] Master@127.0.218.126:43973 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (7162 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:17:01.219483   873 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.0.218.126:44019
I20260812 06:17:01.220048   873 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:01.222813  1108 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:17:01.222846  1106 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:17:01.222856   873 server_base.cc:1061] running on GCE node
W20260812 06:17:01.222914  1105 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:17:01.223387   873 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:01.223448   873 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:17:01.223465   873 hybrid_clock.cc:648] HybridClock initialized: now 1786515421223466 us; error 0 us; skew 500 ppm
I20260812 06:17:01.224367   873 webserver.cc:533] Webserver started at http://127.0.218.126:38819/ using document root <none> and password file <none>
I20260812 06:17:01.224601   873 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:01.224684   873 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:01.224771   873 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:01.225229   873 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/master-0-root/instance:
uuid: "9a2960f2b38b4a3fadee20d36bd4fa67"
format_stamp: "Formatted at 2026-08-12 06:17:01 on dist-test-slave-8tdl"
I20260812 06:17:01.226984   873 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:17:01.228173  1113 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:17:01.228475   873 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:01.228570   873 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/master-0-root
uuid: "9a2960f2b38b4a3fadee20d36bd4fa67"
format_stamp: "Formatted at 2026-08-12 06:17:01 on dist-test-slave-8tdl"
I20260812 06:17:01.228662   873 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-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:17:01.260501   873 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:01.261054   873 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:01.266259   873 rpc_server.cc:307] RPC server started. Bound to: 127.0.218.126:44019
I20260812 06:17:01.267017  1178 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.218.126:44019 every 8 connection(s)
I20260812 06:17:01.268502  1179 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:17:01.279798  1179 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67: Bootstrap starting.
I20260812 06:17:01.280980  1179 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:01.282349  1179 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67: No bootstrap required, opened a new log
I20260812 06:17:01.282903  1179 raft_consensus.cc:359] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a2960f2b38b4a3fadee20d36bd4fa67" member_type: VOTER }
I20260812 06:17:01.283047  1179 raft_consensus.cc:385] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:01.283099  1179 raft_consensus.cc:740] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 9a2960f2b38b4a3fadee20d36bd4fa67, State: Initialized, Role: FOLLOWER
I20260812 06:17:01.283298  1179 consensus_queue.cc:260] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [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: "9a2960f2b38b4a3fadee20d36bd4fa67" member_type: VOTER }
I20260812 06:17:01.283411  1179 raft_consensus.cc:399] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:01.283465  1179 raft_consensus.cc:493] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:01.283524  1179 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:01.284327  1179 raft_consensus.cc:515] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a2960f2b38b4a3fadee20d36bd4fa67" member_type: VOTER }
I20260812 06:17:01.284500  1179 leader_election.cc:304] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [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: 9a2960f2b38b4a3fadee20d36bd4fa67; no voters: 
I20260812 06:17:01.284754  1179 leader_election.cc:290] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:01.285022  1182 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:01.285261  1179 sys_catalog.cc:565] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:17:01.285305  1182 raft_consensus.cc:697] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 1 LEADER]: Becoming Leader. State: Replica: 9a2960f2b38b4a3fadee20d36bd4fa67, State: Running, Role: LEADER
I20260812 06:17:01.285514  1182 consensus_queue.cc:237] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [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: "9a2960f2b38b4a3fadee20d36bd4fa67" member_type: VOTER }
I20260812 06:17:01.286144  1184 sys_catalog.cc:455] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 9a2960f2b38b4a3fadee20d36bd4fa67. Latest consensus state: current_term: 1 leader_uuid: "9a2960f2b38b4a3fadee20d36bd4fa67" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a2960f2b38b4a3fadee20d36bd4fa67" member_type: VOTER } }
I20260812 06:17:01.286235  1184 sys_catalog.cc:458] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:01.286405  1183 sys_catalog.cc:455] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "9a2960f2b38b4a3fadee20d36bd4fa67" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a2960f2b38b4a3fadee20d36bd4fa67" member_type: VOTER } }
I20260812 06:17:01.286520  1183 sys_catalog.cc:458] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [sys.catalog]: This master's current role is: LEADER
I20260812 06:17:01.286875  1191 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:17:01.287740  1191 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:17:01.288067   873 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:17:01.289935  1191 catalog_manager.cc:1383] Generated new cluster ID: 7c1b9440f38b41d391b56f67ecef2873
I20260812 06:17:01.290058  1191 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:17:01.304447  1191 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:17:01.305187  1191 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:17:01.314605  1191 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67: Generated new TSK 0
I20260812 06:17:01.314939  1191 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:17:01.320958   873 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:17:01.323356  1203 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:17:01.323410   873 server_base.cc:1061] running on GCE node
W20260812 06:17:01.323511  1200 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:17:01.323521  1201 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:17:01.323912   873 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:17:01.323984   873 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:17:01.324028   873 hybrid_clock.cc:648] HybridClock initialized: now 1786515421324027 us; error 0 us; skew 500 ppm
I20260812 06:17:01.325096   873 webserver.cc:533] Webserver started at http://127.0.218.65:45905/ using document root <none> and password file <none>
I20260812 06:17:01.325300   873 fs_manager.cc:362] Metadata directory not provided
I20260812 06:17:01.325383   873 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:17:01.325491   873 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:17:01.325966   873 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/instance:
uuid: "fedad2661da24f44a982b6a17b91c0df"
format_stamp: "Formatted at 2026-08-12 06:17:01 on dist-test-slave-8tdl"
I20260812 06:17:01.327767   873 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:01.329021  1209 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:17:01.329398   873 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:17:01.329516   873 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root
uuid: "fedad2661da24f44a982b6a17b91c0df"
format_stamp: "Formatted at 2026-08-12 06:17:01 on dist-test-slave-8tdl"
I20260812 06:17:01.329644   873 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-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:17:01.373494   873 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:17:01.373921   873 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:17:01.374245   873 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:17:01.374854   873 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:17:01.374895   873 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:01.374953   873 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:17:01.374992   873 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:17:01.379655   873 rpc_server.cc:307] RPC server started. Bound to: 127.0.218.65:45563
I20260812 06:17:01.379688  1285 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.0.218.65:45563 every 8 connection(s)
I20260812 06:17:01.388911  1286 heartbeater.cc:344] Connected to a master server at 127.0.218.126:44019
I20260812 06:17:01.389073  1286 heartbeater.cc:461] Registering TS with master...
I20260812 06:17:01.389415  1286 heartbeater.cc:507] Master 127.0.218.126:44019 requested a full tablet report, sending...
I20260812 06:17:01.390141  1134 ts_manager.cc:194] Registered new tserver with Master: fedad2661da24f44a982b6a17b91c0df (127.0.218.65:45563)
I20260812 06:17:01.390316   873 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.010177589s
I20260812 06:17:01.391005  1134 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:35594
I20260812 06:17:01.398509  1134 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:35596:
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:17:01.408756  1242 tablet_service.cc:1511] Processing CreateTablet for tablet 9f6dc7f5e92744d48c65f94e942a881a (DEFAULT_TABLE table=heavy-update-compaction-test [id=e5b4c0d444294986bbf39b3516c88916]), partition=
I20260812 06:17:01.409142  1242 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9f6dc7f5e92744d48c65f94e942a881a. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:17:01.411723  1300 tablet_bootstrap.cc:492] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Bootstrap starting.
I20260812 06:17:01.412719  1300 tablet_bootstrap.cc:654] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Neither blocks nor log segments found. Creating new log.
I20260812 06:17:01.413995  1300 tablet_bootstrap.cc:492] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: No bootstrap required, opened a new log
I20260812 06:17:01.414136  1300 ts_tablet_manager.cc:1403] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:17:01.414650  1300 raft_consensus.cc:359] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fedad2661da24f44a982b6a17b91c0df" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 45563 } }
I20260812 06:17:01.414848  1300 raft_consensus.cc:385] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:17:01.414904  1300 raft_consensus.cc:740] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: fedad2661da24f44a982b6a17b91c0df, State: Initialized, Role: FOLLOWER
I20260812 06:17:01.415073  1300 consensus_queue.cc:260] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [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: "fedad2661da24f44a982b6a17b91c0df" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 45563 } }
I20260812 06:17:01.415194  1300 raft_consensus.cc:399] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:17:01.415246  1300 raft_consensus.cc:493] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:17:01.415308  1300 raft_consensus.cc:3060] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:17:01.416157  1300 raft_consensus.cc:515] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fedad2661da24f44a982b6a17b91c0df" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 45563 } }
I20260812 06:17:01.416339  1300 leader_election.cc:304] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [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: fedad2661da24f44a982b6a17b91c0df; no voters: 
I20260812 06:17:01.416610  1300 leader_election.cc:290] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:17:01.416743  1302 raft_consensus.cc:2804] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:17:01.417011  1300 ts_tablet_manager.cc:1434] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:17:01.417100  1286 heartbeater.cc:499] Master 127.0.218.126:44019 was elected leader, sending a full tablet report...
I20260812 06:17:01.417332  1302 raft_consensus.cc:697] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 1 LEADER]: Becoming Leader. State: Replica: fedad2661da24f44a982b6a17b91c0df, State: Running, Role: LEADER
I20260812 06:17:01.417480  1302 consensus_queue.cc:237] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [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: "fedad2661da24f44a982b6a17b91c0df" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 45563 } }
I20260812 06:17:01.419189  1134 catalog_manager.cc:5719] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df reported cstate change: term changed from 0 to 1, leader changed from <none> to fedad2661da24f44a982b6a17b91c0df (127.0.218.65). New cstate: current_term: 1 leader_uuid: "fedad2661da24f44a982b6a17b91c0df" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "fedad2661da24f44a982b6a17b91c0df" member_type: VOTER last_known_addr { host: "127.0.218.65" port: 45563 } health_report { overall_health: HEALTHY } } }
I20260812 06:17:01.482134   873 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.057s	user 0.015s	sys 0.010s
I20260812 06:17:01.630741  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=19.054940
I20260812 06:17:01.801538  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.170s	user 0.135s	sys 0.032s Metrics: {"bytes_written":12307490,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":100,"dirs.run_cpu_time_us":210,"dirs.run_wall_time_us":891,"drs_written":1,"lbm_read_time_us":91,"lbm_reads_lt_1ms":4,"lbm_write_time_us":43281,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"update_count":1500}
I20260812 06:17:01.802376  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling LogGCOp(9f6dc7f5e92744d48c65f94e942a881a): free 20743880 bytes of WAL
I20260812 06:17:01.802757  1214 log_reader.cc:385] T 9f6dc7f5e92744d48c65f94e942a881a: removed 2 log segments from log reader
I20260812 06:17:01.802843  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000001 (ops 1-6)
I20260812 06:17:01.802915  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000002 (ops 7-11)
I20260812 06:17:01.807898  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: LogGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.005s	user 0.000s	sys 0.004s Metrics: {}
I20260812 06:17:01.808481  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:01.826629  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.018s	user 0.005s	sys 0.010s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6357,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:01.827356  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:01.992421  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.165s	user 0.128s	sys 0.031s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":511,"lbm_read_time_us":10252,"lbm_reads_lt_1ms":464,"lbm_write_time_us":27875,"lbm_writes_lt_1ms":443,"mutex_wait_us":78,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":15616,"thread_start_us":336,"threads_started":5,"update_count":2000}
I20260812 06:17:01.993088  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=14.095187
I20260812 06:17:02.044428  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.051s	user 0.030s	sys 0.016s Metrics: {"bytes_written":16409937,"delete_count":0,"lbm_write_time_us":21171,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:02.044937  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a): 16411402 bytes on disk
I20260812 06:17:02.045364  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4}
I20260812 06:17:02.045791  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:02.195344  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.149s	user 0.081s	sys 0.063s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672193,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":578,"lbm_read_time_us":10496,"lbm_reads_lt_1ms":463,"lbm_write_time_us":24568,"lbm_writes_lt_1ms":443,"mutex_wait_us":250,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":2000}
I20260812 06:17:02.195972  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=14.095187
I20260812 06:17:02.244989  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.049s	user 0.035s	sys 0.008s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":20082,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:02.245539  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:02.256448  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4215,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:02.257164  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:02.454908  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.198s	user 0.109s	sys 0.081s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":897,"lbm_read_time_us":12070,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30380,"lbm_writes_lt_1ms":543,"mutex_wait_us":26,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":17664,"update_count":2500}
I20260812 06:17:02.456063  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=14.095187
I20260812 06:17:02.511504  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.055s	user 0.038s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":27185,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:17:02.512112  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:02.525537  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.013s	user 0.004s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5134,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:02.526158  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:02.700793  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.174s	user 0.121s	sys 0.049s 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":509,"lbm_read_time_us":11741,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33712,"lbm_writes_lt_1ms":543,"mutex_wait_us":71,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3200,"update_count":2500}
I20260812 06:17:02.701417  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=11.118625
I20260812 06:17:02.736063  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.034s	user 0.029s	sys 0.004s Metrics: {"bytes_written":12717736,"delete_count":0,"lbm_write_time_us":14879,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:17:02.736877  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:02.753111  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.016s	user 0.005s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5611,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:02.753779  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:02.886194  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.132s	user 0.099s	sys 0.032s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672269,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1998,"lbm_read_time_us":9111,"lbm_reads_lt_1ms":464,"lbm_write_time_us":26289,"lbm_writes_lt_1ms":443,"mutex_wait_us":1258,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":6016,"update_count":2000}
I20260812 06:17:02.887212  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=10.126437
I20260812 06:17:02.939625  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.052s	user 0.028s	sys 0.011s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16800,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:17:02.940282  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:02.957070  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.016s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5987,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:02.957695  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:03.098812  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.141s	user 0.110s	sys 0.028s 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":2104,"lbm_read_time_us":9576,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26540,"lbm_writes_lt_1ms":443,"mutex_wait_us":445,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":9728,"update_count":2000}
I20260812 06:17:03.099658  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=10.126437
I20260812 06:17:03.154484  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.055s	user 0.019s	sys 0.032s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":20929,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:17:03.155435  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:03.168053  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.012s	user 0.010s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4647,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:03.168558  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:03.200251  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.032s	user 0.026s	sys 0.004s Metrics: {"bytes_written":1234473,"cfile_init":1,"dirs.queue_time_us":76,"dirs.run_cpu_time_us":281,"dirs.run_wall_time_us":1505,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1554,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:17:03.200876  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling LogGCOp(9f6dc7f5e92744d48c65f94e942a881a): free 120553373 bytes of WAL
I20260812 06:17:03.201107  1214 log_reader.cc:385] T 9f6dc7f5e92744d48c65f94e942a881a: removed 12 log segments from log reader
I20260812 06:17:03.201148  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000003 (ops 12-16)
I20260812 06:17:03.201179  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000004 (ops 17-20)
I20260812 06:17:03.201243  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000005 (ops 21-25)
I20260812 06:17:03.201272  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000006 (ops 26-30)
I20260812 06:17:03.201310  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000007 (ops 31-34)
I20260812 06:17:03.201359  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000008 (ops 35-39)
I20260812 06:17:03.201392  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000009 (ops 40-44)
I20260812 06:17:03.201442  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000010 (ops 45-49)
I20260812 06:17:03.201483  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000011 (ops 50-54)
I20260812 06:17:03.201521  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000012 (ops 55-59)
I20260812 06:17:03.201558  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000013 (ops 60-64)
I20260812 06:17:03.201596  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000014 (ops 65-69)
I20260812 06:17:03.229681  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: LogGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.029s	user 0.000s	sys 0.026s Metrics: {}
I20260812 06:17:03.230386  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=5.165500
I20260812 06:17:03.248704  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.018s	user 0.008s	sys 0.008s Metrics: {"bytes_written":6441039,"delete_count":0,"lbm_write_time_us":7267,"lbm_writes_lt_1ms":160,"reinsert_count":0,"update_count":785}
I20260812 06:17:03.249253  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a): 472 bytes on disk
I20260812 06:17:03.249701  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4}
I20260812 06:17:03.250183  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:03.258442  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.008s	user 0.006s	sys 0.000s Metrics: {"bytes_written":1764227,"delete_count":0,"lbm_write_time_us":2547,"lbm_writes_lt_1ms":46,"reinsert_count":0,"update_count":215}
I20260812 06:17:03.259246  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:03.476464  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.217s	user 0.120s	sys 0.092s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877282,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1231,"lbm_read_time_us":13374,"lbm_reads_lt_1ms":666,"lbm_write_time_us":34036,"lbm_writes_lt_1ms":643,"mutex_wait_us":425,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":6656,"thread_start_us":83,"threads_started":1,"update_count":3000}
I20260812 06:17:03.477052  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=16.079562
I20260812 06:17:03.537664  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.060s	user 0.040s	sys 0.017s Metrics: {"bytes_written":18543158,"delete_count":0,"lbm_write_time_us":27136,"lbm_writes_lt_1ms":455,"reinsert_count":0,"update_count":2260}
I20260812 06:17:03.538214  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.196750
I20260812 06:17:03.556303  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.018s	user 0.009s	sys 0.000s Metrics: {"bytes_written":2379608,"delete_count":0,"lbm_write_time_us":3567,"lbm_writes_lt_1ms":61,"reinsert_count":0,"update_count":290}
I20260812 06:17:03.556807  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:03.566674  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.010s	user 0.006s	sys 0.002s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3617,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:03.567281  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:03.776767  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.209s	user 0.147s	sys 0.062s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877171,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":731,"lbm_read_time_us":13774,"lbm_reads_lt_1ms":673,"lbm_write_time_us":33200,"lbm_writes_lt_1ms":643,"mutex_wait_us":311,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":21504,"update_count":3000}
I20260812 06:17:03.777608  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=15.087375
I20260812 06:17:03.844442  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.067s	user 0.038s	sys 0.024s Metrics: {"bytes_written":16820145,"delete_count":0,"lbm_write_time_us":29909,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":412,"reinsert_count":0,"update_count":2050}
I20260812 06:17:03.844923  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:03.857012  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.012s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4640,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:03.857535  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:03.869359  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4122,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:03.870044  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:04.088445  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.218s	user 0.142s	sys 0.075s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877209,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":706,"lbm_read_time_us":15306,"lbm_reads_lt_1ms":673,"lbm_write_time_us":37419,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":7168,"update_count":3000}
I20260812 06:17:04.089313  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=14.095187
I20260812 06:17:04.149313  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.060s	user 0.041s	sys 0.015s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":23146,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:04.149971  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:04.161975  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.012s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4650,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:04.162500  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:04.345037  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.182s	user 0.120s	sys 0.062s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":634,"lbm_read_time_us":12020,"lbm_reads_lt_1ms":568,"lbm_write_time_us":32242,"lbm_writes_lt_1ms":543,"mutex_wait_us":286,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7040,"update_count":2500}
I20260812 06:17:04.345781  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=14.095187
I20260812 06:17:04.405489  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.060s	user 0.026s	sys 0.029s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":25717,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:17:04.406134  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:04.424903  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.019s	user 0.014s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7382,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:04.425405  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:04.592904  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.167s	user 0.123s	sys 0.044s 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":334,"lbm_read_time_us":13170,"lbm_reads_lt_1ms":568,"lbm_write_time_us":26625,"lbm_writes_lt_1ms":543,"mutex_wait_us":72,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":9088,"update_count":2500}
I20260812 06:17:04.593921  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=14.095187
I20260812 06:17:04.652958  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.059s	user 0.020s	sys 0.035s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":19967,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:17:04.653690  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:04.672129  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.018s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6882,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:04.672819  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:04.713210  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.040s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1193509,"cfile_init":1,"dirs.queue_time_us":79,"dirs.run_cpu_time_us":250,"dirs.run_wall_time_us":1398,"drs_written":1,"lbm_read_time_us":111,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1538,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:17:04.714049  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling LogGCOp(9f6dc7f5e92744d48c65f94e942a881a): free 124710318 bytes of WAL
I20260812 06:17:04.714349  1214 log_reader.cc:385] T 9f6dc7f5e92744d48c65f94e942a881a: removed 12 log segments from log reader
I20260812 06:17:04.714433  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000015 (ops 70-74)
I20260812 06:17:04.714515  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000016 (ops 75-79)
I20260812 06:17:04.714564  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000017 (ops 80-84)
I20260812 06:17:04.714609  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000018 (ops 85-89)
I20260812 06:17:04.714651  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000019 (ops 90-94)
I20260812 06:17:04.714736  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000020 (ops 95-99)
I20260812 06:17:04.714779  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000021 (ops 100-104)
I20260812 06:17:04.714836  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000022 (ops 105-109)
I20260812 06:17:04.714879  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000023 (ops 110-114)
I20260812 06:17:04.714920  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000024 (ops 115-119)
I20260812 06:17:04.714958  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000025 (ops 120-124)
I20260812 06:17:04.715004  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000026 (ops 125-129)
I20260812 06:17:04.749358  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: LogGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.035s	user 0.001s	sys 0.031s Metrics: {}
I20260812 06:17:04.750218  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a): 463 bytes on disk
I20260812 06:17:04.751223  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.001s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":85,"lbm_reads_lt_1ms":4}
I20260812 06:17:04.752246  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=3.181125
I20260812 06:17:04.772369  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.020s	user 0.009s	sys 0.008s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":7411,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:04.772799  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:04.782758  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.010s	user 0.010s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3655,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:04.783211  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:05.028832  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.245s	user 0.169s	sys 0.072s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979742,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":941,"lbm_read_time_us":15829,"lbm_reads_lt_1ms":774,"lbm_write_time_us":41995,"lbm_writes_lt_1ms":743,"mutex_wait_us":23,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":11520,"thread_start_us":100,"threads_started":1,"update_count":3500}
I20260812 06:17:05.029685  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=15.087375
I20260812 06:17:05.089316  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.059s	user 0.026s	sys 0.027s Metrics: {"bytes_written":16820143,"delete_count":0,"lbm_write_time_us":24032,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:17:05.089815  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:05.107815  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.018s	user 0.008s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6618,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.108299  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:05.118570  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.010s	user 0.001s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3782,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:05.119151  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:05.331226  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.212s	user 0.143s	sys 0.049s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877207,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":1850,"lbm_read_time_us":11360,"lbm_reads_lt_1ms":673,"lbm_write_time_us":37515,"lbm_writes_lt_1ms":643,"mutex_wait_us":572,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":13696,"update_count":3000}
I20260812 06:17:05.332052  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=15.087375
I20260812 06:17:05.420907  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.089s	user 0.035s	sys 0.012s Metrics: {"bytes_written":16820145,"delete_count":0,"lbm_write_time_us":20528,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:17:05.421617  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=6.157687
I20260812 06:17:05.448920  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.027s	user 0.013s	sys 0.012s Metrics: {"bytes_written":7794838,"delete_count":0,"lbm_write_time_us":11688,"lbm_writes_lt_1ms":193,"reinsert_count":0,"update_count":950}
I20260812 06:17:05.449442  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:05.508984  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.059s	user 0.005s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3999,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.509492  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:05.535072  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.025s	user 0.008s	sys 0.008s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6168,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.535838  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:05.548072  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.012s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4567,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.548858  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:05.801932  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.253s	user 0.211s	sys 0.039s Metrics: {"cfile_cache_miss":935,"cfile_cache_miss_bytes":41184697,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":115,"lbm_read_time_us":16036,"lbm_reads_lt_1ms":975,"lbm_write_time_us":53000,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":942,"mutex_wait_us":52,"peak_mem_usage":112822188,"reinsert_count":0,"spinlock_wait_cycles":10496,"update_count":4500}
I20260812 06:17:05.802836  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=18.063937
I20260812 06:17:05.877390  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.071s	user 0.036s	sys 0.032s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":26178,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:05.878139  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:05.897128  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.019s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6654,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:05.897660  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:06.136621  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.239s	user 0.147s	sys 0.091s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877101,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":257,"lbm_read_time_us":14240,"lbm_reads_lt_1ms":664,"lbm_write_time_us":39230,"lbm_writes_lt_1ms":643,"mutex_wait_us":29,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":25728,"update_count":3000}
I20260812 06:17:06.137344  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=18.063937
I20260812 06:17:06.203186  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.066s	user 0.041s	sys 0.022s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":28645,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:06.203682  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:06.216008  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.012s	user 0.005s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4203,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:06.216850  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:06.244110  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushMRSOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.027s	user 0.025s	sys 0.001s Metrics: {"bytes_written":1193505,"cfile_init":1,"dirs.queue_time_us":123,"dirs.run_cpu_time_us":249,"dirs.run_wall_time_us":1472,"drs_written":1,"lbm_read_time_us":39,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1601,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:17:06.244776  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling LogGCOp(9f6dc7f5e92744d48c65f94e942a881a): free 112239502 bytes of WAL
I20260812 06:17:06.245003  1214 log_reader.cc:385] T 9f6dc7f5e92744d48c65f94e942a881a: removed 11 log segments from log reader
I20260812 06:17:06.245045  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000027 (ops 130-134)
I20260812 06:17:06.245074  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000028 (ops 135-139)
I20260812 06:17:06.245136  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000029 (ops 140-144)
I20260812 06:17:06.245168  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000030 (ops 145-149)
I20260812 06:17:06.245211  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000031 (ops 150-154)
I20260812 06:17:06.245249  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000032 (ops 155-158)
I20260812 06:17:06.245290  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000033 (ops 159-163)
I20260812 06:17:06.245329  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000034 (ops 164-168)
I20260812 06:17:06.245370  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000035 (ops 169-173)
I20260812 06:17:06.245409  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000036 (ops 174-178)
I20260812 06:17:06.245452  1214 log.cc:1079] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: Deleting log segment in path: /tmp/dist-test-taskr6Urdt/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515414030787-873-0/minicluster-data/ts-0-root/wals/9f6dc7f5e92744d48c65f94e942a881a/wal-000000037 (ops 179-183)
I20260812 06:17:06.270617  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: LogGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.026s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:17:06.271081  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a): 462 bytes on disk
I20260812 06:17:06.271515  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: UndoDeltaBlockGCOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":56,"lbm_reads_lt_1ms":4}
I20260812 06:17:06.272060  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=3.181125
I20260812 06:17:06.287659  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.015s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":4391,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:17:06.288138  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:06.298750  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.010s	user 0.003s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3913,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:17:06.299230  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:06.546996  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.248s	user 0.175s	sys 0.072s Metrics: {"cfile_cache_miss":834,"cfile_cache_miss_bytes":37082156,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":920,"lbm_read_time_us":19465,"lbm_reads_lt_1ms":874,"lbm_write_time_us":47458,"lbm_writes_lt_1ms":843,"mutex_wait_us":54,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":11520,"thread_start_us":86,"threads_started":1,"update_count":4000}
I20260812 06:17:06.547798  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=18.063937
I20260812 06:17:06.600726   873 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.118s	user 1.903s	sys 0.159s
I20260812 06:17:06.609445  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.061s	user 0.032s	sys 0.028s Metrics: {"bytes_written":20512321,"delete_count":0,"lbm_write_time_us":29304,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:17:06.610044  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=2.188937
I20260812 06:17:06.621136  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: FlushDeltaMemStoresOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.011s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4827,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:17:06.621671  1287 maintenance_manager.cc:419] P fedad2661da24f44a982b6a17b91c0df: Scheduling MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a): perf score=1.000000
I20260812 06:17:06.633653   873 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.032s	user 0.001s	sys 0.000s
I20260812 06:17:06.634356   873 tablet_server.cc:179] TabletServer@127.0.218.65:0 shutting down...
I20260812 06:17:06.770571  1214 maintenance_manager.cc:643] P fedad2661da24f44a982b6a17b91c0df: MajorDeltaCompactionOp(9f6dc7f5e92744d48c65f94e942a881a) complete. Timing: real 0.149s	user 0.088s	sys 0.060s Metrics: {"cfile_cache_hit":30,"cfile_cache_hit_bytes":4262390,"cfile_cache_miss":602,"cfile_cache_miss_bytes":24614718,"cfile_init":4,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":377,"lbm_read_time_us":9410,"lbm_reads_lt_1ms":618,"lbm_write_time_us":29317,"lbm_writes_lt_1ms":643,"mutex_wait_us":30,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":77184,"update_count":3000}
I20260812 06:17:06.771458   873 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:17:06.771708   873 tablet_replica.cc:333] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df: stopping tablet replica
I20260812 06:17:06.771840   873 raft_consensus.cc:2243] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:06.772039   873 raft_consensus.cc:2272] T 9f6dc7f5e92744d48c65f94e942a881a P fedad2661da24f44a982b6a17b91c0df [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:06.775631   873 tablet_server.cc:196] TabletServer@127.0.218.65:0 shutdown complete.
I20260812 06:17:06.823796   873 master.cc:562] Master@127.0.218.126:44019 shutting down...
I20260812 06:17:06.827461   873 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:17:06.827646   873 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:17:06.827697   873 tablet_replica.cc:333] T 00000000000000000000000000000000 P 9a2960f2b38b4a3fadee20d36bd4fa67: stopping tablet replica
I20260812 06:17:06.840106   873 master.cc:584] Master@127.0.218.126:44019 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5727 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (12891 ms total)

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