[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:18:50.716835 19272 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.18.210.62:42165
I20260812 06:18:50.718103 19272 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:18:50.718868 19272 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:50.726279 19277 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:50.726316 19278 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:50.726532 19272 server_base.cc:1061] running on GCE node
W20260812 06:18:50.726611 19280 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:50.727197 19272 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:50.727299 19272 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:18:50.727334 19272 hybrid_clock.cc:648] HybridClock initialized: now 1786515530727331 us; error 0 us; skew 500 ppm
I20260812 06:18:50.729351 19272 webserver.cc:533] Webserver started at http://127.18.210.62:33705/ using document root <none> and password file <none>
I20260812 06:18:50.729969 19272 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:50.730022 19272 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:50.730266 19272 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:50.732204 19272 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/master-0-root/instance:
uuid: "065e288deadf47c69c4d41eada847900"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-nfb5"
I20260812 06:18:50.736202 19272 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.000s
I20260812 06:18:50.738490 19285 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:50.739741 19272 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
I20260812 06:18:50.739848 19272 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/master-0-root
uuid: "065e288deadf47c69c4d41eada847900"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-nfb5"
I20260812 06:18:50.739938 19272 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:18:50.754874 19272 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:50.755688 19272 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:18:50.755846 19272 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:50.764984 19272 rpc_server.cc:307] RPC server started. Bound to: 127.18.210.62:42165
I20260812 06:18:50.765043 19367 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.210.62:42165 every 8 connection(s)
I20260812 06:18:50.768196 19368 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:50.774518 19368 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900: Bootstrap starting.
I20260812 06:18:50.777349 19368 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:50.778452 19368 log.cc:826] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900: Log is configured to *not* fsync() on all Append() calls
I20260812 06:18:50.780388 19368 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900: No bootstrap required, opened a new log
I20260812 06:18:50.783478 19368 raft_consensus.cc:359] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "065e288deadf47c69c4d41eada847900" member_type: VOTER }
I20260812 06:18:50.783655 19368 raft_consensus.cc:385] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:50.783756 19368 raft_consensus.cc:740] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 065e288deadf47c69c4d41eada847900, State: Initialized, Role: FOLLOWER
I20260812 06:18:50.784531 19368 consensus_queue.cc:260] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [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: "065e288deadf47c69c4d41eada847900" member_type: VOTER }
I20260812 06:18:50.784721 19368 raft_consensus.cc:399] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:50.784796 19368 raft_consensus.cc:493] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:50.784988 19368 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:50.785812 19368 raft_consensus.cc:515] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "065e288deadf47c69c4d41eada847900" member_type: VOTER }
I20260812 06:18:50.786316 19368 leader_election.cc:304] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [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: 065e288deadf47c69c4d41eada847900; no voters: 
I20260812 06:18:50.786682 19368 leader_election.cc:290] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:50.786839 19371 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:50.787127 19371 raft_consensus.cc:697] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 1 LEADER]: Becoming Leader. State: Replica: 065e288deadf47c69c4d41eada847900, State: Running, Role: LEADER
I20260812 06:18:50.787563 19371 consensus_queue.cc:237] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [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: "065e288deadf47c69c4d41eada847900" member_type: VOTER }
I20260812 06:18:50.787804 19368 sys_catalog.cc:565] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:50.789772 19374 sys_catalog.cc:455] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "065e288deadf47c69c4d41eada847900" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "065e288deadf47c69c4d41eada847900" member_type: VOTER } }
I20260812 06:18:50.789752 19375 sys_catalog.cc:455] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 065e288deadf47c69c4d41eada847900. Latest consensus state: current_term: 1 leader_uuid: "065e288deadf47c69c4d41eada847900" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "065e288deadf47c69c4d41eada847900" member_type: VOTER } }
I20260812 06:18:50.789917 19374 sys_catalog.cc:458] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:50.789917 19375 sys_catalog.cc:458] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:50.790390 19272 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
W20260812 06:18:50.793169 19398 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:18:50.793288 19398 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:18:50.793383 19397 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:50.794566 19397 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:50.801546 19397 catalog_manager.cc:1383] Generated new cluster ID: 51b7cb31592d48cfa892ecf65b84537b
I20260812 06:18:50.801723 19397 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:50.812862 19397 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:50.813781 19397 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:50.823490 19397 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900: Generated new TSK 0
I20260812 06:18:50.824427 19397 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:50.855435 19272 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:18:50.858822 19404 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:50.858829 19405 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:50.859086 19272 server_base.cc:1061] running on GCE node
W20260812 06:18:50.859277 19411 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:50.859539 19272 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:50.859601 19272 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:18:50.859633 19272 hybrid_clock.cc:648] HybridClock initialized: now 1786515530859633 us; error 0 us; skew 500 ppm
I20260812 06:18:50.860812 19272 webserver.cc:533] Webserver started at http://127.18.210.1:34503/ using document root <none> and password file <none>
I20260812 06:18:50.861007 19272 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:50.861076 19272 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:50.861176 19272 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:50.861722 19272 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/instance:
uuid: "0f1258b823ab45f98ebcb18a8afd60ce"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-nfb5"
I20260812 06:18:50.863795 19272 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.001s	sys 0.002s
I20260812 06:18:50.864944 19422 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:50.865252 19272 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:18:50.865345 19272 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root
uuid: "0f1258b823ab45f98ebcb18a8afd60ce"
format_stamp: "Formatted at 2026-08-12 06:18:50 on dist-test-slave-nfb5"
I20260812 06:18:50.865440 19272 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:18:50.879686 19272 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:50.880231 19272 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:50.880858 19272 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:50.881844 19272 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:50.881924 19272 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:50.882010 19272 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:50.882053 19272 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:50.890017 19272 rpc_server.cc:307] RPC server started. Bound to: 127.18.210.1:38613
I20260812 06:18:50.890056 19523 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.210.1:38613 every 8 connection(s)
I20260812 06:18:50.901458 19525 heartbeater.cc:344] Connected to a master server at 127.18.210.62:42165
I20260812 06:18:50.901751 19525 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:50.902303 19525 heartbeater.cc:507] Master 127.18.210.62:42165 requested a full tablet report, sending...
I20260812 06:18:50.903972 19315 ts_manager.cc:194] Registered new tserver with Master: 0f1258b823ab45f98ebcb18a8afd60ce (127.18.210.1:38613)
I20260812 06:18:50.904369 19272 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.013400866s
I20260812 06:18:50.905635 19315 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:54104
I20260812 06:18:50.915655 19315 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:54110:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:18:50.934430 19468 tablet_service.cc:1511] Processing CreateTablet for tablet dcdb342c18a84630ad52789e27910ea0 (DEFAULT_TABLE table=heavy-update-compaction-test [id=39848eb2df2b437baff4b1e101dc9384]), partition=
I20260812 06:18:50.935079 19468 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet dcdb342c18a84630ad52789e27910ea0. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:50.938484 19551 tablet_bootstrap.cc:492] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Bootstrap starting.
I20260812 06:18:50.939832 19551 tablet_bootstrap.cc:654] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:50.941977 19551 tablet_bootstrap.cc:492] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: No bootstrap required, opened a new log
I20260812 06:18:50.942106 19551 ts_tablet_manager.cc:1403] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Time spent bootstrapping tablet: real 0.004s	user 0.003s	sys 0.000s
I20260812 06:18:50.942670 19551 raft_consensus.cc:359] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0f1258b823ab45f98ebcb18a8afd60ce" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 38613 } }
I20260812 06:18:50.942786 19551 raft_consensus.cc:385] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:50.942811 19551 raft_consensus.cc:740] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0f1258b823ab45f98ebcb18a8afd60ce, State: Initialized, Role: FOLLOWER
I20260812 06:18:50.942996 19551 consensus_queue.cc:260] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [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: "0f1258b823ab45f98ebcb18a8afd60ce" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 38613 } }
I20260812 06:18:50.943084 19551 raft_consensus.cc:399] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:50.943137 19551 raft_consensus.cc:493] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:50.943197 19551 raft_consensus.cc:3060] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:50.944265 19551 raft_consensus.cc:515] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0f1258b823ab45f98ebcb18a8afd60ce" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 38613 } }
I20260812 06:18:50.944490 19551 leader_election.cc:304] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [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: 0f1258b823ab45f98ebcb18a8afd60ce; no voters: 
I20260812 06:18:50.944710 19551 leader_election.cc:290] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:50.944802 19553 raft_consensus.cc:2804] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:50.944978 19553 raft_consensus.cc:697] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 1 LEADER]: Becoming Leader. State: Replica: 0f1258b823ab45f98ebcb18a8afd60ce, State: Running, Role: LEADER
I20260812 06:18:50.945099 19551 ts_tablet_manager.cc:1434] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:18:50.945194 19553 consensus_queue.cc:237] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [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: "0f1258b823ab45f98ebcb18a8afd60ce" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 38613 } }
I20260812 06:18:50.945482 19525 heartbeater.cc:499] Master 127.18.210.62:42165 was elected leader, sending a full tablet report...
I20260812 06:18:50.948767 19315 catalog_manager.cc:5719] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce reported cstate change: term changed from 0 to 1, leader changed from <none> to 0f1258b823ab45f98ebcb18a8afd60ce (127.18.210.1). New cstate: current_term: 1 leader_uuid: "0f1258b823ab45f98ebcb18a8afd60ce" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0f1258b823ab45f98ebcb18a8afd60ce" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 38613 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:51.027127 19272 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.071s	user 0.013s	sys 0.022s
I20260812 06:18:51.141934 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushMRSOp(dcdb342c18a84630ad52789e27910ea0): perf score=14.094003
I20260812 06:18:51.301421 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushMRSOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.159s	user 0.114s	sys 0.039s Metrics: {"bytes_written":8205078,"cfile_init":1,"compiler_manager_pool.queue_time_us":359,"delete_count":0,"dirs.queue_time_us":58,"dirs.run_cpu_time_us":235,"dirs.run_wall_time_us":1060,"drs_written":1,"lbm_read_time_us":89,"lbm_reads_lt_1ms":4,"lbm_write_time_us":40640,"lbm_writes_lt_1ms":557,"peak_mem_usage":0,"reinsert_count":0,"rows_written":103,"thread_start_us":155,"threads_started":1,"update_count":1000}
I20260812 06:18:51.302737 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:51.417281 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.114s	user 0.089s	sys 0.025s Metrics: {"cfile_cache_miss":231,"cfile_cache_miss_bytes":12426369,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":67,"lbm_read_time_us":6114,"lbm_reads_lt_1ms":263,"lbm_write_time_us":21315,"lbm_writes_lt_1ms":243,"peak_mem_usage":25836184,"reinsert_count":0,"thread_start_us":269,"threads_started":5,"update_count":1000}
I20260812 06:18:51.417886 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling LogGCOp(dcdb342c18a84630ad52789e27910ea0): free 8725963 bytes of WAL
I20260812 06:18:51.418232 19429 log_reader.cc:385] T dcdb342c18a84630ad52789e27910ea0: removed 1 log segments from log reader
I20260812 06:18:51.418356 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000001 (ops 1-6)
I20260812 06:18:51.421051 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: LogGCOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.003s	user 0.000s	sys 0.001s Metrics: {}
I20260812 06:18:51.421579 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=7.149875
I20260812 06:18:51.457856 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.036s	user 0.015s	sys 0.017s Metrics: {"bytes_written":8615323,"delete_count":0,"lbm_write_time_us":13882,"lbm_writes_lt_1ms":213,"reinsert_count":0,"update_count":1050}
I20260812 06:18:51.458448 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0): 12308958 bytes on disk
I20260812 06:18:51.459059 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":48,"lbm_reads_lt_1ms":4}
I20260812 06:18:51.459506 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:51.470655 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4372,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:51.473208 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:51.615414 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.142s	user 0.100s	sys 0.035s Metrics: {"cfile_cache_miss":332,"cfile_cache_miss_bytes":16528891,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":884,"lbm_read_time_us":10478,"lbm_reads_lt_1ms":372,"lbm_write_time_us":20659,"lbm_writes_lt_1ms":343,"mutex_wait_us":44,"peak_mem_usage":38262756,"reinsert_count":0,"spinlock_wait_cycles":11008,"update_count":1500}
I20260812 06:18:51.616180 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:51.664685 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.048s	user 0.031s	sys 0.008s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":17542,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:51.665230 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:51.677075 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4545,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:51.677927 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:51.821254 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.143s	user 0.098s	sys 0.044s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631313,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":296,"lbm_read_time_us":8974,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28739,"lbm_writes_lt_1ms":443,"mutex_wait_us":28,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":9984,"update_count":2000}
I20260812 06:18:51.822089 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:51.872936 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.051s	user 0.027s	sys 0.012s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18002,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:51.873471 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:51.885532 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.012s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4552,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:51.886410 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:52.048312 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.162s	user 0.131s	sys 0.020s 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":524,"lbm_read_time_us":10467,"lbm_reads_lt_1ms":472,"lbm_write_time_us":30446,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":2000}
I20260812 06:18:52.049090 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:52.105525 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.056s	user 0.028s	sys 0.019s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":19893,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:52.106196 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:52.265465 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.159s	user 0.093s	sys 0.064s Metrics: {"cfile_cache_miss":331,"cfile_cache_miss_bytes":16528781,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":79,"lbm_read_time_us":11428,"lbm_reads_lt_1ms":363,"lbm_write_time_us":25210,"lbm_writes_lt_1ms":343,"peak_mem_usage":38262756,"reinsert_count":0,"update_count":1500}
I20260812 06:18:52.266232 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:52.306632 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.040s	user 0.036s	sys 0.000s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17280,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:52.307273 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:52.327301 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.020s	user 0.019s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7493,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:52.328086 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:52.478164 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.150s	user 0.126s	sys 0.024s 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":204,"lbm_read_time_us":9994,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28394,"lbm_writes_lt_1ms":443,"mutex_wait_us":46,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":50816,"update_count":2000}
I20260812 06:18:52.478765 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:52.525489 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.047s	user 0.027s	sys 0.014s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":19033,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:52.526118 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:52.539153 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.013s	user 0.009s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4628,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:52.539846 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:52.678597 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.139s	user 0.105s	sys 0.033s 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":744,"lbm_read_time_us":10996,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26123,"lbm_writes_lt_1ms":443,"mutex_wait_us":329,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":28288,"update_count":2000}
I20260812 06:18:52.679431 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:52.733654 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.054s	user 0.023s	sys 0.022s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18452,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:52.734311 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:52.747763 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.013s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5287,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:52.748389 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushMRSOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:52.791926 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushMRSOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.043s	user 0.032s	sys 0.004s Metrics: {"bytes_written":1152509,"cfile_init":1,"dirs.queue_time_us":59,"dirs.run_cpu_time_us":282,"dirs.run_wall_time_us":1335,"drs_written":1,"lbm_read_time_us":81,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1619,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:18:52.793290 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling LogGCOp(dcdb342c18a84630ad52789e27910ea0): free 115943167 bytes of WAL
I20260812 06:18:52.793566 19429 log_reader.cc:385] T dcdb342c18a84630ad52789e27910ea0: removed 11 log segments from log reader
I20260812 06:18:52.793617 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000002 (ops 7-11)
I20260812 06:18:52.793673 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000003 (ops 12-16)
I20260812 06:18:52.793713 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000004 (ops 17-21)
I20260812 06:18:52.793761 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000005 (ops 22-26)
I20260812 06:18:52.793793 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000006 (ops 27-31)
I20260812 06:18:52.793833 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000007 (ops 32-36)
I20260812 06:18:52.793867 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000008 (ops 37-41)
I20260812 06:18:52.793907 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000009 (ops 42-46)
I20260812 06:18:52.793947 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000010 (ops 47-51)
I20260812 06:18:52.793983 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000011 (ops 52-56)
I20260812 06:18:52.794023 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000012 (ops 57-61)
I20260812 06:18:52.824132 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: LogGCOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.031s	user 0.000s	sys 0.029s Metrics: {}
I20260812 06:18:52.824575 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=3.181125
I20260812 06:18:52.851974 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.027s	user 0.014s	sys 0.001s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":6259,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:52.852511 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:52.863183 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.010s	user 0.010s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4022,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:52.863718 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0): 448 bytes on disk
I20260812 06:18:52.864207 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0) 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:18:52.864776 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:53.094547 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.230s	user 0.156s	sys 0.072s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28836364,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":571,"lbm_read_time_us":15447,"lbm_reads_lt_1ms":674,"lbm_write_time_us":39013,"lbm_writes_lt_1ms":643,"mutex_wait_us":23,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":12928,"thread_start_us":100,"threads_started":1,"update_count":3000}
I20260812 06:18:53.096024 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=14.095187
I20260812 06:18:53.145856 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.050s	user 0.019s	sys 0.027s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":22622,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:53.146589 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:53.320134 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.173s	user 0.097s	sys 0.075s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20631193,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":415,"lbm_read_time_us":11369,"lbm_reads_lt_1ms":467,"lbm_write_time_us":29275,"lbm_writes_lt_1ms":443,"mutex_wait_us":1,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:18:53.320834 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:53.361174 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.040s	user 0.018s	sys 0.020s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17494,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:53.361876 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:53.383076 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.021s	user 0.012s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7840,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:53.383731 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:53.533051 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.149s	user 0.123s	sys 0.024s 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":1061,"lbm_read_time_us":11199,"lbm_reads_lt_1ms":464,"lbm_write_time_us":28468,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":2000}
I20260812 06:18:53.533835 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:53.585945 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.052s	user 0.023s	sys 0.017s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":18965,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:53.586673 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:53.600283 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.013s	user 0.010s	sys 0.002s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5013,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:53.600993 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:53.761545 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.160s	user 0.128s	sys 0.032s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631313,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1919,"lbm_read_time_us":12676,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28255,"lbm_writes_lt_1ms":443,"mutex_wait_us":654,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2000}
I20260812 06:18:53.763042 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:53.810266 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.047s	user 0.019s	sys 0.025s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":21507,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:53.810887 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:53.823526 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.012s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4481,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:53.824086 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:53.962150 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.138s	user 0.097s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631313,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":240,"lbm_read_time_us":10851,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26020,"lbm_writes_lt_1ms":443,"mutex_wait_us":42,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2000}
I20260812 06:18:53.962863 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:54.020009 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.057s	user 0.015s	sys 0.032s Metrics: {"bytes_written":12307489,"delete_count":0,"lbm_write_time_us":17495,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:54.020707 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:54.033113 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.012s	user 0.009s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4779,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:54.033648 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:54.206851 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.173s	user 0.114s	sys 0.053s 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":272,"lbm_read_time_us":12773,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27532,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2688,"update_count":2000}
I20260812 06:18:54.207535 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:54.265542 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.058s	user 0.034s	sys 0.013s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":24038,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:18:54.266117 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:54.283954 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.018s	user 0.013s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6499,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:54.284723 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:54.440017 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.155s	user 0.119s	sys 0.036s 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":619,"lbm_read_time_us":13476,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28779,"lbm_writes_lt_1ms":443,"mutex_wait_us":37,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":5248,"update_count":2000}
I20260812 06:18:54.440819 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=10.126437
I20260812 06:18:54.497370 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.056s	user 0.031s	sys 0.012s Metrics: {"bytes_written":12307493,"delete_count":0,"lbm_write_time_us":20484,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:54.498091 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:54.516923 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.019s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7095,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:54.517621 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushMRSOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:54.550858 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushMRSOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.033s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":62,"dirs.run_cpu_time_us":215,"dirs.run_wall_time_us":1209,"drs_written":1,"lbm_read_time_us":86,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2201,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:18:54.551785 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling LogGCOp(dcdb342c18a84630ad52789e27910ea0): free 133024364 bytes of WAL
I20260812 06:18:54.552073 19429 log_reader.cc:385] T dcdb342c18a84630ad52789e27910ea0: removed 13 log segments from log reader
I20260812 06:18:54.552124 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000013 (ops 62-66)
I20260812 06:18:54.552155 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000014 (ops 67-70)
I20260812 06:18:54.552222 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000015 (ops 71-75)
I20260812 06:18:54.552268 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000016 (ops 76-80)
I20260812 06:18:54.552311 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000017 (ops 81-85)
I20260812 06:18:54.552352 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000018 (ops 86-90)
I20260812 06:18:54.552397 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000019 (ops 91-95)
I20260812 06:18:54.552435 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000020 (ops 96-100)
I20260812 06:18:54.552479 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000021 (ops 101-105)
I20260812 06:18:54.552515 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000022 (ops 106-110)
I20260812 06:18:54.552552 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000023 (ops 111-115)
I20260812 06:18:54.552613 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000024 (ops 116-120)
I20260812 06:18:54.552652 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000025 (ops 121-125)
I20260812 06:18:54.583909 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: LogGCOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.032s	user 0.000s	sys 0.031s Metrics: {}
I20260812 06:18:54.584371 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:54.606889 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.022s	user 0.012s	sys 0.004s Metrics: {"bytes_written":4184708,"delete_count":0,"lbm_write_time_us":7288,"lbm_writes_lt_1ms":105,"reinsert_count":0,"update_count":510}
I20260812 06:18:54.607506 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:54.618855 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.011s	user 0.000s	sys 0.009s Metrics: {"bytes_written":4020608,"delete_count":0,"lbm_write_time_us":4596,"lbm_writes_lt_1ms":101,"reinsert_count":0,"update_count":490}
I20260812 06:18:54.619407 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:54.832261 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.213s	user 0.142s	sys 0.060s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28836375,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1035,"lbm_read_time_us":15564,"lbm_reads_lt_1ms":674,"lbm_write_time_us":40027,"lbm_writes_lt_1ms":643,"mutex_wait_us":72,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3968,"thread_start_us":111,"threads_started":1,"update_count":3000}
I20260812 06:18:54.833082 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0): 473 bytes on disk
I20260812 06:18:54.833669 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":101,"lbm_reads_lt_1ms":4}
I20260812 06:18:54.834498 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=14.095187
I20260812 06:18:54.893391 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.059s	user 0.044s	sys 0.007s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":24129,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:54.893981 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:54.907094 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.013s	user 0.009s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4819,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:54.907668 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:55.094033 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.186s	user 0.134s	sys 0.045s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733727,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":359,"lbm_read_time_us":12773,"lbm_reads_lt_1ms":572,"lbm_write_time_us":35000,"lbm_writes_lt_1ms":543,"mutex_wait_us":18,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2500}
I20260812 06:18:55.094810 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=11.118625
I20260812 06:18:55.138031 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.043s	user 0.039s	sys 0.001s Metrics: {"bytes_written":12717734,"delete_count":0,"lbm_write_time_us":19548,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:55.138895 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:55.155712 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.016s	user 0.011s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6120,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:55.156266 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:55.310792 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.154s	user 0.112s	sys 0.035s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631302,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":192,"lbm_read_time_us":10180,"lbm_reads_lt_1ms":468,"lbm_write_time_us":26977,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:18:55.311405 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=11.118625
I20260812 06:18:55.364320 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.053s	user 0.036s	sys 0.016s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":18971,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:55.364933 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:55.394551 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.029s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5969,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:55.395241 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:55.406551 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4311,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:55.407416 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:55.603654 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.196s	user 0.144s	sys 0.049s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24733834,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":334,"lbm_read_time_us":14589,"lbm_reads_lt_1ms":573,"lbm_write_time_us":34056,"lbm_writes_lt_1ms":543,"mutex_wait_us":106,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2176,"update_count":2500}
I20260812 06:18:55.604473 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=11.118625
I20260812 06:18:55.638893 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.034s	user 0.013s	sys 0.017s Metrics: {"bytes_written":12717737,"delete_count":0,"lbm_write_time_us":14934,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:55.639905 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:55.659183 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.019s	user 0.010s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":7439,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:55.659729 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:55.830129 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.170s	user 0.111s	sys 0.057s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631305,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":141,"lbm_read_time_us":11884,"lbm_reads_lt_1ms":464,"lbm_write_time_us":27350,"lbm_writes_lt_1ms":443,"mutex_wait_us":20,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":127360,"update_count":2000}
I20260812 06:18:55.831041 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=11.118625
I20260812 06:18:55.876385 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.045s	user 0.035s	sys 0.009s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":19467,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:55.876950 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:55.904410 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.027s	user 0.006s	sys 0.008s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6110,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:55.905061 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:55.918452 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.013s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4510,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:55.919164 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:56.103621 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.184s	user 0.125s	sys 0.056s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24733834,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":392,"lbm_read_time_us":14062,"lbm_reads_lt_1ms":573,"lbm_write_time_us":39164,"lbm_writes_lt_1ms":543,"mutex_wait_us":56,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":6784,"update_count":2500}
I20260812 06:18:56.104537 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=11.118625
I20260812 06:18:56.145859 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.041s	user 0.024s	sys 0.015s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":17156,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:56.146754 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:56.168278 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.021s	user 0.011s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6862,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:56.168962 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushMRSOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:56.213826 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushMRSOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.045s	user 0.031s	sys 0.000s Metrics: {"bytes_written":1234475,"cfile_init":1,"dirs.queue_time_us":184,"dirs.run_cpu_time_us":274,"dirs.run_wall_time_us":1579,"drs_written":1,"lbm_read_time_us":45,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2172,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:18:56.214872 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0): 472 bytes on disk
I20260812 06:18:56.215415 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: UndoDeltaBlockGCOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":55,"lbm_reads_lt_1ms":4}
I20260812 06:18:56.216125 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=3.181125
I20260812 06:18:56.231278 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.015s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4512903,"delete_count":0,"lbm_write_time_us":5702,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:18:56.231880 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling LogGCOp(dcdb342c18a84630ad52789e27910ea0): free 124710557 bytes of WAL
I20260812 06:18:56.232174 19429 log_reader.cc:385] T dcdb342c18a84630ad52789e27910ea0: removed 12 log segments from log reader
I20260812 06:18:56.232241 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000026 (ops 126-130)
I20260812 06:18:56.232287 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000027 (ops 131-135)
I20260812 06:18:56.232324 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000028 (ops 136-140)
I20260812 06:18:56.232353 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000029 (ops 141-145)
I20260812 06:18:56.232391 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000030 (ops 146-150)
I20260812 06:18:56.232427 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000031 (ops 151-155)
I20260812 06:18:56.232461 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000032 (ops 156-160)
I20260812 06:18:56.232496 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000033 (ops 161-165)
I20260812 06:18:56.232520 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000034 (ops 166-170)
I20260812 06:18:56.232581 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000035 (ops 171-175)
I20260812 06:18:56.232609 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000036 (ops 176-180)
I20260812 06:18:56.232642 19429 log.cc:1079] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/dcdb342c18a84630ad52789e27910ea0/wal-000000037 (ops 181-185)
I20260812 06:18:56.265599 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: LogGCOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.034s	user 0.000s	sys 0.031s Metrics: {}
I20260812 06:18:56.266243 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:56.293038 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.027s	user 0.011s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6604,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:56.293632 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:56.314739 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.021s	user 0.010s	sys 0.007s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4686,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:56.315529 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:56.579437 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.264s	user 0.168s	sys 0.093s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":32938885,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":3995,"lbm_read_time_us":18816,"lbm_reads_lt_1ms":775,"lbm_write_time_us":46126,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":7552,"thread_start_us":138,"threads_started":1,"update_count":3500}
I20260812 06:18:56.580446 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=14.095187
I20260812 06:18:56.643150 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.062s	user 0.030s	sys 0.031s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":22496,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:56.643952 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=2.188937
I20260812 06:18:56.661196 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.017s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6978,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:56.661765 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0): perf score=1.000000
I20260812 06:18:56.761332 19272 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.734s	user 2.120s	sys 0.162s
I20260812 06:18:56.851830 19272 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.090s	user 0.004s	sys 0.000s
I20260812 06:18:56.852618 19272 tablet_server.cc:179] TabletServer@127.18.210.1:0 shutting down...
I20260812 06:18:56.853987 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: MajorDeltaCompactionOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.192s	user 0.114s	sys 0.075s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24733726,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":364,"lbm_read_time_us":13661,"lbm_reads_lt_1ms":564,"lbm_write_time_us":33438,"lbm_writes_lt_1ms":543,"mutex_wait_us":2,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:18:56.854754 19526 maintenance_manager.cc:419] P 0f1258b823ab45f98ebcb18a8afd60ce: Scheduling FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0): perf score=6.157687
I20260812 06:18:56.879365 19429 maintenance_manager.cc:643] P 0f1258b823ab45f98ebcb18a8afd60ce: FlushDeltaMemStoresOp(dcdb342c18a84630ad52789e27910ea0) complete. Timing: real 0.024s	user 0.019s	sys 0.004s Metrics: {"bytes_written":8205078,"delete_count":0,"lbm_write_time_us":10585,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:18:56.880049 19272 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:18:56.880595 19272 tablet_replica.cc:333] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce: stopping tablet replica
I20260812 06:18:56.880914 19272 raft_consensus.cc:2243] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:56.881222 19272 raft_consensus.cc:2272] T dcdb342c18a84630ad52789e27910ea0 P 0f1258b823ab45f98ebcb18a8afd60ce [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:56.897020 19272 tablet_server.cc:196] TabletServer@127.18.210.1:0 shutdown complete.
I20260812 06:18:56.902891 19272 master.cc:562] Master@127.18.210.62:42165 shutting down...
I20260812 06:18:56.907500 19272 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:18:56.907702 19272 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:18:56.907770 19272 tablet_replica.cc:333] T 00000000000000000000000000000000 P 065e288deadf47c69c4d41eada847900: stopping tablet replica
I20260812 06:18:56.920286 19272 master.cc:584] Master@127.18.210.62:42165 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (6310 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:18:57.041268 19272 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.18.210.62:42549
I20260812 06:18:57.041786 19272 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:18:57.044706 19272 server_base.cc:1061] running on GCE node
W20260812 06:18:57.044729 19591 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:57.044837 19585 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:57.044706 19584 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:57.045233 19272 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:57.045281 19272 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:18:57.045334 19272 hybrid_clock.cc:648] HybridClock initialized: now 1786515537045333 us; error 0 us; skew 500 ppm
I20260812 06:18:57.046363 19272 webserver.cc:533] Webserver started at http://127.18.210.62:42671/ using document root <none> and password file <none>
I20260812 06:18:57.046586 19272 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:57.046669 19272 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:57.046772 19272 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:57.047353 19272 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/master-0-root/instance:
uuid: "0cfff0d52224468783be171c92bea4f6"
format_stamp: "Formatted at 2026-08-12 06:18:57 on dist-test-slave-nfb5"
I20260812 06:18:57.049252 19272 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:18:57.050391 19598 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:57.050791 19272 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:18:57.050866 19272 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/master-0-root
uuid: "0cfff0d52224468783be171c92bea4f6"
format_stamp: "Formatted at 2026-08-12 06:18:57 on dist-test-slave-nfb5"
I20260812 06:18:57.050997 19272 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:18:57.068224 19272 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:57.068655 19272 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:57.073751 19272 rpc_server.cc:307] RPC server started. Bound to: 127.18.210.62:42549
I20260812 06:18:57.079236 19688 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.210.62:42549 every 8 connection(s)
I20260812 06:18:57.079830 19689 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:57.082135 19689 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6: Bootstrap starting.
I20260812 06:18:57.083089 19689 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:57.084249 19689 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6: No bootstrap required, opened a new log
I20260812 06:18:57.084731 19689 raft_consensus.cc:359] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0cfff0d52224468783be171c92bea4f6" member_type: VOTER }
I20260812 06:18:57.084862 19689 raft_consensus.cc:385] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:57.084916 19689 raft_consensus.cc:740] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0cfff0d52224468783be171c92bea4f6, State: Initialized, Role: FOLLOWER
I20260812 06:18:57.085099 19689 consensus_queue.cc:260] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [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: "0cfff0d52224468783be171c92bea4f6" member_type: VOTER }
I20260812 06:18:57.085211 19689 raft_consensus.cc:399] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:57.085263 19689 raft_consensus.cc:493] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:57.085321 19689 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:57.086109 19689 raft_consensus.cc:515] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0cfff0d52224468783be171c92bea4f6" member_type: VOTER }
I20260812 06:18:57.086282 19689 leader_election.cc:304] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [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: 0cfff0d52224468783be171c92bea4f6; no voters: 
I20260812 06:18:57.086491 19689 leader_election.cc:290] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:57.086658 19694 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:57.086988 19694 raft_consensus.cc:697] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 1 LEADER]: Becoming Leader. State: Replica: 0cfff0d52224468783be171c92bea4f6, State: Running, Role: LEADER
I20260812 06:18:57.087193 19689 sys_catalog.cc:565] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:18:57.087188 19694 consensus_queue.cc:237] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [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: "0cfff0d52224468783be171c92bea4f6" member_type: VOTER }
I20260812 06:18:57.087751 19696 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 0cfff0d52224468783be171c92bea4f6. Latest consensus state: current_term: 1 leader_uuid: "0cfff0d52224468783be171c92bea4f6" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0cfff0d52224468783be171c92bea4f6" member_type: VOTER } }
I20260812 06:18:57.087909 19696 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:57.088012 19695 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "0cfff0d52224468783be171c92bea4f6" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0cfff0d52224468783be171c92bea4f6" member_type: VOTER } }
I20260812 06:18:57.088141 19695 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [sys.catalog]: This master's current role is: LEADER
I20260812 06:18:57.088548 19706 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:18:57.089470 19706 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:18:57.089679 19272 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:18:57.091460 19706 catalog_manager.cc:1383] Generated new cluster ID: 4ad20601208142a0b3fbc88d61617794
I20260812 06:18:57.091519 19706 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:18:57.101532 19706 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:18:57.102063 19706 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:18:57.115410 19706 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6: Generated new TSK 0
I20260812 06:18:57.115602 19706 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:18:57.122282 19272 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:18:57.124598 19272 server_base.cc:1061] running on GCE node
W20260812 06:18:57.124607 19724 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:57.124717 19725 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:18:57.124748 19728 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:18:57.125051 19272 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:18:57.125101 19272 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:18:57.125120 19272 hybrid_clock.cc:648] HybridClock initialized: now 1786515537125121 us; error 0 us; skew 500 ppm
I20260812 06:18:57.126066 19272 webserver.cc:533] Webserver started at http://127.18.210.1:38433/ using document root <none> and password file <none>
I20260812 06:18:57.126271 19272 fs_manager.cc:362] Metadata directory not provided
I20260812 06:18:57.126327 19272 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:18:57.126442 19272 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:18:57.127007 19272 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/instance:
uuid: "51c34ac44e454e1d979d7bf36a61bdaf"
format_stamp: "Formatted at 2026-08-12 06:18:57 on dist-test-slave-nfb5"
I20260812 06:18:57.128756 19272 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:18:57.129783 19735 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:57.130102 19272 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:18:57.130167 19272 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root
uuid: "51c34ac44e454e1d979d7bf36a61bdaf"
format_stamp: "Formatted at 2026-08-12 06:18:57 on dist-test-slave-nfb5"
I20260812 06:18:57.130224 19272 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:18:57.139566 19272 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:18:57.139896 19272 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:18:57.140170 19272 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:18:57.140671 19272 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:18:57.140722 19272 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:57.140785 19272 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:18:57.140825 19272 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:18:57.145584 19272 rpc_server.cc:307] RPC server started. Bound to: 127.18.210.1:37617
I20260812 06:18:57.146040 19844 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.210.1:37617 every 8 connection(s)
I20260812 06:18:57.156507 19845 heartbeater.cc:344] Connected to a master server at 127.18.210.62:42549
I20260812 06:18:57.156639 19845 heartbeater.cc:461] Registering TS with master...
I20260812 06:18:57.156868 19845 heartbeater.cc:507] Master 127.18.210.62:42549 requested a full tablet report, sending...
I20260812 06:18:57.157656 19629 ts_manager.cc:194] Registered new tserver with Master: 51c34ac44e454e1d979d7bf36a61bdaf (127.18.210.1:37617)
I20260812 06:18:57.158437 19272 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.012191575s
I20260812 06:18:57.158558 19629 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:45368
I20260812 06:18:57.165789 19629 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:45378:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:18:57.175282 19784 tablet_service.cc:1511] Processing CreateTablet for tablet 6b18186b82c44c1a9ec6beeda7efa225 (DEFAULT_TABLE table=heavy-update-compaction-test [id=b0aa745d07504c43acbea997f5601f5a]), partition=
I20260812 06:18:57.175609 19784 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 6b18186b82c44c1a9ec6beeda7efa225. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:18:57.177958 19862 tablet_bootstrap.cc:492] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Bootstrap starting.
I20260812 06:18:57.178884 19862 tablet_bootstrap.cc:654] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Neither blocks nor log segments found. Creating new log.
I20260812 06:18:57.180112 19862 tablet_bootstrap.cc:492] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: No bootstrap required, opened a new log
I20260812 06:18:57.180212 19862 ts_tablet_manager.cc:1403] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Time spent bootstrapping tablet: real 0.002s	user 0.000s	sys 0.002s
I20260812 06:18:57.180785 19862 raft_consensus.cc:359] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "51c34ac44e454e1d979d7bf36a61bdaf" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 37617 } }
I20260812 06:18:57.180881 19862 raft_consensus.cc:385] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:18:57.180904 19862 raft_consensus.cc:740] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 51c34ac44e454e1d979d7bf36a61bdaf, State: Initialized, Role: FOLLOWER
I20260812 06:18:57.181100 19862 consensus_queue.cc:260] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [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: "51c34ac44e454e1d979d7bf36a61bdaf" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 37617 } }
I20260812 06:18:57.181206 19862 raft_consensus.cc:399] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:18:57.181264 19862 raft_consensus.cc:493] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:18:57.181325 19862 raft_consensus.cc:3060] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:18:57.182121 19862 raft_consensus.cc:515] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "51c34ac44e454e1d979d7bf36a61bdaf" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 37617 } }
I20260812 06:18:57.182276 19862 leader_election.cc:304] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [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: 51c34ac44e454e1d979d7bf36a61bdaf; no voters: 
I20260812 06:18:57.182513 19862 leader_election.cc:290] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:18:57.182678 19865 raft_consensus.cc:2804] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:18:57.182912 19865 raft_consensus.cc:697] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 1 LEADER]: Becoming Leader. State: Replica: 51c34ac44e454e1d979d7bf36a61bdaf, State: Running, Role: LEADER
I20260812 06:18:57.182972 19862 ts_tablet_manager.cc:1434] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Time spent starting tablet: real 0.003s	user 0.000s	sys 0.003s
I20260812 06:18:57.183000 19845 heartbeater.cc:499] Master 127.18.210.62:42549 was elected leader, sending a full tablet report...
I20260812 06:18:57.183139 19865 consensus_queue.cc:237] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [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: "51c34ac44e454e1d979d7bf36a61bdaf" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 37617 } }
I20260812 06:18:57.184713 19629 catalog_manager.cc:5719] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf reported cstate change: term changed from 0 to 1, leader changed from <none> to 51c34ac44e454e1d979d7bf36a61bdaf (127.18.210.1). New cstate: current_term: 1 leader_uuid: "51c34ac44e454e1d979d7bf36a61bdaf" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "51c34ac44e454e1d979d7bf36a61bdaf" member_type: VOTER last_known_addr { host: "127.18.210.1" port: 37617 } health_report { overall_health: HEALTHY } } }
I20260812 06:18:57.251098 19272 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.060s	user 0.015s	sys 0.010s
I20260812 06:18:57.396881 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=15.086190
I20260812 06:18:57.526111 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.129s	user 0.078s	sys 0.047s Metrics: {"bytes_written":8492252,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":101,"dirs.run_cpu_time_us":351,"dirs.run_wall_time_us":1112,"drs_written":1,"lbm_read_time_us":46,"lbm_reads_lt_1ms":4,"lbm_write_time_us":32951,"lbm_writes_lt_1ms":564,"peak_mem_usage":0,"reinsert_count":0,"rows_written":103,"spinlock_wait_cycles":5376,"update_count":1035}
I20260812 06:18:57.527088 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling LogGCOp(6b18186b82c44c1a9ec6beeda7efa225): free 8725963 bytes of WAL
I20260812 06:18:57.527427 19741 log_reader.cc:385] T 6b18186b82c44c1a9ec6beeda7efa225: removed 1 log segments from log reader
I20260812 06:18:57.527549 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000001 (ops 1-6)
I20260812 06:18:57.530547 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: LogGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.003s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:18:57.531284 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:57.550530 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.019s	user 0.014s	sys 0.001s Metrics: {"bytes_written":3815484,"delete_count":0,"lbm_write_time_us":6207,"lbm_writes_lt_1ms":96,"reinsert_count":0,"update_count":465}
I20260812 06:18:57.551328 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:57.702831 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.151s	user 0.097s	sys 0.049s Metrics: {"cfile_cache_miss":332,"cfile_cache_miss_bytes":16528899,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":708,"lbm_read_time_us":11534,"lbm_reads_lt_1ms":364,"lbm_write_time_us":20240,"lbm_writes_lt_1ms":343,"peak_mem_usage":38262756,"reinsert_count":0,"spinlock_wait_cycles":1280,"thread_start_us":374,"threads_started":5,"update_count":1500}
I20260812 06:18:57.703612 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225): 12308958 bytes on disk
I20260812 06:18:57.704043 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":43,"lbm_reads_lt_1ms":4}
I20260812 06:18:57.704478 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:18:57.754969 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.050s	user 0.004s	sys 0.027s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16634,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:57.755671 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:57.775568 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.020s	user 0.019s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":7037,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:57.776281 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:57.941874 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.165s	user 0.127s	sys 0.037s 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":698,"lbm_read_time_us":12986,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28861,"lbm_writes_lt_1ms":443,"mutex_wait_us":260,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":50304,"update_count":2000}
I20260812 06:18:57.944056 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:18:57.996603 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.052s	user 0.022s	sys 0.020s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":19641,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:57.997390 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:58.012120 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.014s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5085,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.012934 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:58.174216 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.161s	user 0.141s	sys 0.020s 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":1077,"lbm_read_time_us":12356,"lbm_reads_lt_1ms":472,"lbm_write_time_us":30374,"lbm_writes_lt_1ms":443,"mutex_wait_us":91,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":25472,"update_count":2000}
I20260812 06:18:58.174996 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:18:58.244503 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.069s	user 0.027s	sys 0.032s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":21471,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:58.245257 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:58.258296 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5126,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.258844 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:58.436919 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.178s	user 0.108s	sys 0.069s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631313,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":220,"lbm_read_time_us":14163,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29269,"lbm_writes_lt_1ms":443,"mutex_wait_us":22,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":12928,"update_count":2000}
I20260812 06:18:58.437769 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:18:58.487792 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.050s	user 0.014s	sys 0.031s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":21192,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:18:58.488524 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:58.503810 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.015s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5065,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.504747 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:58.662817 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.158s	user 0.108s	sys 0.049s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631313,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":204,"lbm_read_time_us":11843,"lbm_reads_lt_1ms":472,"lbm_write_time_us":31668,"lbm_writes_lt_1ms":443,"mutex_wait_us":39,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":18560,"update_count":2000}
I20260812 06:18:58.665884 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:18:58.712709 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.047s	user 0.026s	sys 0.018s Metrics: {"bytes_written":12307493,"delete_count":0,"lbm_write_time_us":20577,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":301,"reinsert_count":0,"update_count":1500}
I20260812 06:18:58.713693 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:58.732333 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.018s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7299,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.732860 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:58.884657 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.152s	user 0.125s	sys 0.022s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631315,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":376,"lbm_read_time_us":11158,"lbm_reads_lt_1ms":468,"lbm_write_time_us":28828,"lbm_writes_lt_1ms":443,"mutex_wait_us":107,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":43136,"update_count":2000}
I20260812 06:18:58.885648 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:18:58.941154 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.055s	user 0.017s	sys 0.023s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18997,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:58.941802 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:58.956167 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.014s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5537,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:58.957170 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:59.113281 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.156s	user 0.103s	sys 0.052s 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":618,"lbm_read_time_us":10764,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27900,"lbm_writes_lt_1ms":443,"mutex_wait_us":43,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":9088,"update_count":2000}
I20260812 06:18:59.114346 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:18:59.167126 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.052s	user 0.032s	sys 0.018s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":18408,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:18:59.167856 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:59.187623 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.020s	user 0.015s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7321,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:59.188432 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:59.228801 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.040s	user 0.037s	sys 0.001s Metrics: {"bytes_written":1275444,"cfile_init":1,"dirs.queue_time_us":96,"dirs.run_cpu_time_us":333,"dirs.run_wall_time_us":1531,"drs_written":1,"lbm_read_time_us":73,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2138,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:18:59.229732 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225): 484 bytes on disk
I20260812 06:18:59.230181 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":50,"lbm_reads_lt_1ms":4}
I20260812 06:18:59.230798 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:59.407725 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.177s	user 0.123s	sys 0.053s 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":229,"lbm_read_time_us":12432,"lbm_reads_lt_1ms":464,"lbm_write_time_us":27582,"lbm_writes_lt_1ms":443,"mutex_wait_us":53,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":2000}
I20260812 06:18:59.408900 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling LogGCOp(6b18186b82c44c1a9ec6beeda7efa225): free 136728234 bytes of WAL
I20260812 06:18:59.409274 19741 log_reader.cc:385] T 6b18186b82c44c1a9ec6beeda7efa225: removed 13 log segments from log reader
I20260812 06:18:59.409353 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000002 (ops 7-11)
I20260812 06:18:59.409482 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000003 (ops 12-16)
I20260812 06:18:59.409554 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000004 (ops 17-21)
I20260812 06:18:59.409641 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000005 (ops 22-26)
I20260812 06:18:59.409700 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000006 (ops 27-31)
I20260812 06:18:59.409781 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000007 (ops 32-36)
I20260812 06:18:59.409839 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000008 (ops 37-41)
I20260812 06:18:59.409922 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000009 (ops 42-46)
I20260812 06:18:59.409981 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000010 (ops 47-51)
I20260812 06:18:59.410064 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000011 (ops 52-56)
I20260812 06:18:59.410121 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000012 (ops 57-61)
I20260812 06:18:59.410177 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000013 (ops 62-66)
I20260812 06:18:59.410231 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000014 (ops 67-71)
I20260812 06:18:59.442723 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: LogGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.034s	user 0.000s	sys 0.030s Metrics: {}
I20260812 06:18:59.443293 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=14.095187
I20260812 06:18:59.499434 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.056s	user 0.022s	sys 0.031s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24771,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:18:59.500062 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:59.512873 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.013s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4623,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:59.513535 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:59.694828 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.181s	user 0.131s	sys 0.047s 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":1176,"lbm_read_time_us":13000,"lbm_reads_lt_1ms":572,"lbm_write_time_us":35343,"lbm_writes_lt_1ms":543,"mutex_wait_us":542,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1536,"update_count":2500}
I20260812 06:18:59.695581 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=11.118625
I20260812 06:18:59.734663 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.039s	user 0.018s	sys 0.018s Metrics: {"bytes_written":12717736,"delete_count":0,"lbm_write_time_us":16484,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:59.735523 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:59.762048 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.026s	user 0.010s	sys 0.001s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5218,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:18:59.762573 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:18:59.774197 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4613,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:18:59.774732 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:18:59.952764 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.178s	user 0.139s	sys 0.035s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24733835,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":634,"lbm_read_time_us":14039,"lbm_reads_lt_1ms":573,"lbm_write_time_us":35363,"lbm_writes_lt_1ms":543,"mutex_wait_us":294,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2688,"update_count":2500}
I20260812 06:18:59.953840 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=11.118625
I20260812 06:18:59.996356 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.042s	user 0.025s	sys 0.016s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":18242,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:18:59.997473 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:00.015226 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.017s	user 0.002s	sys 0.011s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5202,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:00.015820 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:00.169745 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.154s	user 0.106s	sys 0.047s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631303,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":775,"lbm_read_time_us":10752,"lbm_reads_lt_1ms":464,"lbm_write_time_us":27354,"lbm_writes_lt_1ms":443,"mutex_wait_us":335,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":13312,"update_count":2000}
I20260812 06:19:00.170598 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:00.221503 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.051s	user 0.023s	sys 0.023s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":22104,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:19:00.222177 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:00.235878 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.013s	user 0.005s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4707,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:00.236632 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:00.387614 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.151s	user 0.111s	sys 0.040s 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":957,"lbm_read_time_us":10946,"lbm_reads_lt_1ms":472,"lbm_write_time_us":29632,"lbm_writes_lt_1ms":443,"mutex_wait_us":343,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":29440,"update_count":2000}
I20260812 06:19:00.388477 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:00.442634 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.054s	user 0.020s	sys 0.029s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":20093,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:19:00.443300 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:00.456390 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.013s	user 0.007s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5317,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:00.456902 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:00.647125 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.190s	user 0.111s	sys 0.076s 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":2412,"lbm_read_time_us":13376,"lbm_reads_lt_1ms":472,"lbm_write_time_us":32716,"lbm_writes_lt_1ms":443,"mutex_wait_us":1170,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2000}
I20260812 06:19:00.647924 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:00.701730 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.054s	user 0.020s	sys 0.021s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":19352,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:00.702294 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:00.715076 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.013s	user 0.009s	sys 0.003s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4836,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:00.716079 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:00.867410 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.151s	user 0.115s	sys 0.036s 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":191,"lbm_read_time_us":10732,"lbm_reads_lt_1ms":472,"lbm_write_time_us":32298,"lbm_writes_lt_1ms":443,"mutex_wait_us":38,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8448,"update_count":2000}
I20260812 06:19:00.868331 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:00.910382 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.042s	user 0.012s	sys 0.020s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16009,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:00.911058 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:00.953033 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.042s	user 0.030s	sys 0.000s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":63,"dirs.run_cpu_time_us":292,"dirs.run_wall_time_us":1640,"drs_written":1,"lbm_read_time_us":60,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2426,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30}
I20260812 06:19:00.953826 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=3.181125
I20260812 06:19:00.976047 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.022s	user 0.016s	sys 0.003s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":7717,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:19:00.976583 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling LogGCOp(6b18186b82c44c1a9ec6beeda7efa225): free 124710258 bytes of WAL
I20260812 06:19:00.976833 19741 log_reader.cc:385] T 6b18186b82c44c1a9ec6beeda7efa225: removed 12 log segments from log reader
I20260812 06:19:00.976879 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000015 (ops 72-76)
I20260812 06:19:00.976938 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000016 (ops 77-81)
I20260812 06:19:00.976987 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000017 (ops 82-86)
I20260812 06:19:00.977032 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000018 (ops 87-91)
I20260812 06:19:00.977090 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000019 (ops 92-96)
I20260812 06:19:00.977135 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000020 (ops 97-101)
I20260812 06:19:00.977178 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000021 (ops 102-106)
I20260812 06:19:00.977211 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000022 (ops 107-111)
I20260812 06:19:00.977248 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000023 (ops 112-116)
I20260812 06:19:00.977286 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000024 (ops 117-121)
I20260812 06:19:00.977324 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000025 (ops 122-126)
I20260812 06:19:00.977362 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000026 (ops 127-131)
I20260812 06:19:01.006512 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: LogGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.030s	user 0.000s	sys 0.026s Metrics: {}
I20260812 06:19:01.007107 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:01.030357 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.023s	user 0.008s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":6025,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:01.030859 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225): 473 bytes on disk
I20260812 06:19:01.031332 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4}
I20260812 06:19:01.031872 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:01.043334 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4740,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:01.043943 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:01.240765 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.197s	user 0.149s	sys 0.044s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28836361,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":745,"lbm_read_time_us":14864,"lbm_reads_lt_1ms":674,"lbm_write_time_us":37109,"lbm_writes_lt_1ms":643,"mutex_wait_us":41,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":384,"thread_start_us":109,"threads_started":1,"update_count":3000}
I20260812 06:19:01.241369 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=14.095187
I20260812 06:19:01.305974 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.064s	user 0.040s	sys 0.011s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":24746,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:01.306545 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:01.319021 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.012s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4845,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:01.320053 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:01.498188 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.178s	user 0.118s	sys 0.059s 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":283,"lbm_read_time_us":14781,"lbm_reads_lt_1ms":572,"lbm_write_time_us":32215,"lbm_writes_lt_1ms":543,"mutex_wait_us":48,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":86400,"update_count":2500}
I20260812 06:19:01.498823 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=11.118625
I20260812 06:19:01.540669 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.042s	user 0.023s	sys 0.015s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":17484,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:19:01.541606 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:01.556507 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.015s	user 0.008s	sys 0.006s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5365,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:01.557137 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:01.715114 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.158s	user 0.104s	sys 0.053s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631303,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1048,"lbm_read_time_us":12254,"lbm_reads_lt_1ms":464,"lbm_write_time_us":28417,"lbm_writes_lt_1ms":443,"mutex_wait_us":344,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":25088,"update_count":2000}
I20260812 06:19:01.715957 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:01.763211 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.047s	user 0.027s	sys 0.017s Metrics: {"bytes_written":12307493,"delete_count":0,"lbm_write_time_us":20605,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:19:01.763942 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:01.779363 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.015s	user 0.015s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5975,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:01.779906 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:01.941051 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.161s	user 0.117s	sys 0.043s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20631315,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1267,"lbm_read_time_us":12919,"lbm_reads_lt_1ms":464,"lbm_write_time_us":30055,"lbm_writes_lt_1ms":443,"mutex_wait_us":368,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":9344,"update_count":2000}
I20260812 06:19:01.941695 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:01.997368 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.055s	user 0.020s	sys 0.023s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":20007,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:01.998102 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:02.010778 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.012s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5062,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:02.011770 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:02.159086 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.147s	user 0.106s	sys 0.035s 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":588,"lbm_read_time_us":10651,"lbm_reads_lt_1ms":472,"lbm_write_time_us":27971,"lbm_writes_lt_1ms":443,"mutex_wait_us":101,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":9344,"update_count":2000}
I20260812 06:19:02.160044 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:02.210592 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.050s	user 0.024s	sys 0.016s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":19736,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":302,"reinsert_count":0,"update_count":1500}
I20260812 06:19:02.211335 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:02.224790 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.013s	user 0.002s	sys 0.010s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5019,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:02.225559 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:02.372396 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.147s	user 0.106s	sys 0.040s 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":366,"lbm_read_time_us":11949,"lbm_reads_lt_1ms":472,"lbm_write_time_us":28567,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":10624,"update_count":2000}
I20260812 06:19:02.373255 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:02.426362 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.053s	user 0.025s	sys 0.024s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":20076,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:02.427320 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:02.437280 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.010s	user 0.000s	sys 0.004s Metrics: {"bytes_written":1436031,"delete_count":0,"lbm_write_time_us":1763,"lbm_writes_lt_1ms":38,"reinsert_count":0,"update_count":175}
I20260812 06:19:02.437835 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.196750
I20260812 06:19:02.446904 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.009s	user 0.000s	sys 0.007s Metrics: {"bytes_written":2666779,"delete_count":0,"lbm_write_time_us":3446,"lbm_writes_lt_1ms":68,"reinsert_count":0,"update_count":325}
I20260812 06:19:02.447461 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:02.627208 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.180s	user 0.113s	sys 0.065s Metrics: {"cfile_cache_miss":433,"cfile_cache_miss_bytes":20631336,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":759,"lbm_read_time_us":14201,"lbm_reads_lt_1ms":473,"lbm_write_time_us":28688,"lbm_writes_lt_1ms":443,"mutex_wait_us":25,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":16640,"update_count":2000}
I20260812 06:19:02.628208 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=10.126437
I20260812 06:19:02.671000 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.043s	user 0.034s	sys 0.007s Metrics: {"bytes_written":12307493,"delete_count":0,"lbm_write_time_us":18386,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:02.671680 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:02.693491 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.022s	user 0.006s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6695,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:02.694201 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:02.742290 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushMRSOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.048s	user 0.036s	sys 0.000s Metrics: {"bytes_written":1316417,"cfile_init":1,"dirs.queue_time_us":71,"dirs.run_cpu_time_us":220,"dirs.run_wall_time_us":1615,"drs_written":1,"lbm_read_time_us":53,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1960,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32}
I20260812 06:19:02.743288 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225): 492 bytes on disk
I20260812 06:19:02.743750 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: UndoDeltaBlockGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":47,"lbm_reads_lt_1ms":4}
I20260812 06:19:02.744410 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=3.181125
I20260812 06:19:02.764474 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.020s	user 0.008s	sys 0.006s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":4951,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:19:02.765009 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling LogGCOp(6b18186b82c44c1a9ec6beeda7efa225): free 133024705 bytes of WAL
I20260812 06:19:02.765257 19741 log_reader.cc:385] T 6b18186b82c44c1a9ec6beeda7efa225: removed 13 log segments from log reader
I20260812 06:19:02.765302 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000027 (ops 132-136)
I20260812 06:19:02.765348 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000028 (ops 137-141)
I20260812 06:19:02.765408 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000029 (ops 142-146)
I20260812 06:19:02.765448 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000030 (ops 147-150)
I20260812 06:19:02.765509 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000031 (ops 151-155)
I20260812 06:19:02.765575 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000032 (ops 156-160)
I20260812 06:19:02.765611 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000033 (ops 161-165)
I20260812 06:19:02.765643 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000034 (ops 166-170)
I20260812 06:19:02.765681 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000035 (ops 171-175)
I20260812 06:19:02.765717 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000036 (ops 176-180)
I20260812 06:19:02.765754 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000037 (ops 181-185)
I20260812 06:19:02.765791 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000038 (ops 186-190)
I20260812 06:19:02.765826 19741 log.cc:1079] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: Deleting log segment in path: /tmp/dist-test-task9jxUqs/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515530704907-19272-0/minicluster-data/ts-0-root/wals/6b18186b82c44c1a9ec6beeda7efa225/wal-000000039 (ops 191-195)
I20260812 06:19:02.797456 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: LogGCOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.032s	user 0.003s	sys 0.027s Metrics: {}
I20260812 06:19:02.798218 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:02.825066 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.027s	user 0.009s	sys 0.008s Metrics: {"bytes_written":4102660,"delete_count":0,"lbm_write_time_us":7321,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:02.825585 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=2.188937
I20260812 06:19:02.836716 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4324,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:02.837289 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=1.000000
I20260812 06:19:02.944317 19272 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.693s	user 2.070s	sys 0.185s
I20260812 06:19:03.109375 19272 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.164s	user 0.001s	sys 0.000s
I20260812 06:19:03.110081 19272 tablet_server.cc:179] TabletServer@127.18.210.1:0 shutting down...
I20260812 06:19:03.115638 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: MajorDeltaCompactionOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.278s	user 0.147s	sys 0.131s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":32938896,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":521,"lbm_read_time_us":17390,"lbm_reads_lt_1ms":771,"lbm_write_time_us":52289,"lbm_writes_lt_1ms":743,"mutex_wait_us":22,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":21376,"thread_start_us":89,"threads_started":1,"update_count":3500}
I20260812 06:19:03.116427 19846 maintenance_manager.cc:419] P 51c34ac44e454e1d979d7bf36a61bdaf: Scheduling FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225): perf score=6.157687
I20260812 06:19:03.152185 19741 maintenance_manager.cc:643] P 51c34ac44e454e1d979d7bf36a61bdaf: FlushDeltaMemStoresOp(6b18186b82c44c1a9ec6beeda7efa225) complete. Timing: real 0.035s	user 0.019s	sys 0.015s Metrics: {"bytes_written":8205077,"delete_count":0,"lbm_write_time_us":10369,"lbm_writes_lt_1ms":203,"reinsert_count":0,"update_count":1000}
I20260812 06:19:03.152948 19272 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:19:03.153228 19272 tablet_replica.cc:333] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf: stopping tablet replica
I20260812 06:19:03.153369 19272 raft_consensus.cc:2243] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:03.153613 19272 raft_consensus.cc:2272] T 6b18186b82c44c1a9ec6beeda7efa225 P 51c34ac44e454e1d979d7bf36a61bdaf [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:03.157718 19272 tablet_server.cc:196] TabletServer@127.18.210.1:0 shutdown complete.
I20260812 06:19:03.173259 19272 master.cc:562] Master@127.18.210.62:42549 shutting down...
I20260812 06:19:03.177704 19272 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:03.177937 19272 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:03.178042 19272 tablet_replica.cc:333] T 00000000000000000000000000000000 P 0cfff0d52224468783be171c92bea4f6: stopping tablet replica
I20260812 06:19:03.190783 19272 master.cc:584] Master@127.18.210.62:42549 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (6255 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (12566 ms total)

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