[==========] Running 2 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0
WARNING: Logging before InitGoogleLogging() is written to STDERR
I20260812 06:19:16.843946 30837 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.30.29.126:40363
I20260812 06:19:16.845047 30837 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
I20260812 06:19:16.845772 30837 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:16.852706 30843 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:19:16.852769 30837 server_base.cc:1061] running on GCE node
W20260812 06:19:16.852703 30846 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:19:16.853027 30852 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:19:16.853672 30837 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:16.853821 30837 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:19:16.853873 30837 hybrid_clock.cc:648] HybridClock initialized: now 1786515556853870 us; error 0 us; skew 500 ppm
I20260812 06:19:16.855736 30837 webserver.cc:533] Webserver started at http://127.30.29.126:35321/ using document root <none> and password file <none>
I20260812 06:19:16.856274 30837 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:16.856334 30837 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:16.856531 30837 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:16.858314 30837 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/master-0-root/instance:
uuid: "ae561b70e6464316ba25d6237951eaee"
format_stamp: "Formatted at 2026-08-12 06:19:16 on dist-test-slave-gsp7"
I20260812 06:19:16.861965 30837 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:19:16.864192 30864 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:16.865258 30837 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:19:16.865433 30837 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/master-0-root
uuid: "ae561b70e6464316ba25d6237951eaee"
format_stamp: "Formatted at 2026-08-12 06:19:16 on dist-test-slave-gsp7"
I20260812 06:19:16.865597 30837 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:19:16.887677 30837 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:16.888413 30837 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
I20260812 06:19:16.888610 30837 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:16.896864 30837 rpc_server.cc:307] RPC server started. Bound to: 127.30.29.126:40363
I20260812 06:19:16.896883 30940 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.29.126:40363 every 8 connection(s)
I20260812 06:19:16.899247 30942 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:16.904865 30942 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee: Bootstrap starting.
I20260812 06:19:16.907238 30942 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:16.908135 30942 log.cc:826] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee: Log is configured to *not* fsync() on all Append() calls
I20260812 06:19:16.910089 30942 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee: No bootstrap required, opened a new log
I20260812 06:19:16.912891 30942 raft_consensus.cc:359] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ae561b70e6464316ba25d6237951eaee" member_type: VOTER }
I20260812 06:19:16.913064 30942 raft_consensus.cc:385] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:16.913113 30942 raft_consensus.cc:740] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: ae561b70e6464316ba25d6237951eaee, State: Initialized, Role: FOLLOWER
I20260812 06:19:16.913797 30942 consensus_queue.cc:260] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [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: "ae561b70e6464316ba25d6237951eaee" member_type: VOTER }
I20260812 06:19:16.913947 30942 raft_consensus.cc:399] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:16.913996 30942 raft_consensus.cc:493] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:16.914083 30942 raft_consensus.cc:3060] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:16.914891 30942 raft_consensus.cc:515] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ae561b70e6464316ba25d6237951eaee" member_type: VOTER }
I20260812 06:19:16.915282 30942 leader_election.cc:304] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [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: ae561b70e6464316ba25d6237951eaee; no voters: 
I20260812 06:19:16.915613 30942 leader_election.cc:290] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:16.915789 30949 raft_consensus.cc:2804] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:16.916069 30949 raft_consensus.cc:697] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 1 LEADER]: Becoming Leader. State: Replica: ae561b70e6464316ba25d6237951eaee, State: Running, Role: LEADER
I20260812 06:19:16.916491 30949 consensus_queue.cc:237] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [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: "ae561b70e6464316ba25d6237951eaee" member_type: VOTER }
I20260812 06:19:16.916685 30942 sys_catalog.cc:565] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:19:16.918687 30955 sys_catalog.cc:455] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [sys.catalog]: SysCatalogTable state changed. Reason: New leader ae561b70e6464316ba25d6237951eaee. Latest consensus state: current_term: 1 leader_uuid: "ae561b70e6464316ba25d6237951eaee" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ae561b70e6464316ba25d6237951eaee" member_type: VOTER } }
I20260812 06:19:16.918725 30953 sys_catalog.cc:455] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "ae561b70e6464316ba25d6237951eaee" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "ae561b70e6464316ba25d6237951eaee" member_type: VOTER } }
I20260812 06:19:16.918824 30953 sys_catalog.cc:458] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:16.918823 30955 sys_catalog.cc:458] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:16.919253 30837 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
W20260812 06:19:16.921341 30981 catalog_manager.cc:1594] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
W20260812 06:19:16.921438 30981 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
I20260812 06:19:16.921525 30980 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:19:16.922340 30980 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:19:16.927408 30980 catalog_manager.cc:1383] Generated new cluster ID: 7f443ade48764a39964253b76e466541
I20260812 06:19:16.927493 30980 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:19:16.974040 30980 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:19:16.975093 30980 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:19:16.985096 30980 catalog_manager.cc:6092] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee: Generated new TSK 0
I20260812 06:19:16.985896 30980 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:19:17.048641 30837 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:17.051918 30990 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:19:17.052111 30986 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:19:17.052214 30837 server_base.cc:1061] running on GCE node
W20260812 06:19:17.052425 30988 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:19:17.052687 30837 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:17.052732 30837 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:19:17.052748 30837 hybrid_clock.cc:648] HybridClock initialized: now 1786515557052748 us; error 0 us; skew 500 ppm
I20260812 06:19:17.053757 30837 webserver.cc:533] Webserver started at http://127.30.29.65:39935/ using document root <none> and password file <none>
I20260812 06:19:17.053941 30837 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:17.053996 30837 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:17.054098 30837 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:17.054538 30837 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/instance:
uuid: "03f2b67797b84b61bed9844afff218ca"
format_stamp: "Formatted at 2026-08-12 06:19:17 on dist-test-slave-gsp7"
I20260812 06:19:17.056118 30837 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:19:17.057202 31001 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:17.057539 30837 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:17.057639 30837 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root
uuid: "03f2b67797b84b61bed9844afff218ca"
format_stamp: "Formatted at 2026-08-12 06:19:17 on dist-test-slave-gsp7"
I20260812 06:19:17.057735 30837 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:19:17.071477 30837 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:17.071945 30837 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:17.072432 30837 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:19:17.073367 30837 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:19:17.073442 30837 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:17.073544 30837 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:19:17.073589 30837 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:17.079890 30837 rpc_server.cc:307] RPC server started. Bound to: 127.30.29.65:32939
I20260812 06:19:17.080071 31098 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.29.65:32939 every 8 connection(s)
I20260812 06:19:17.090256 31099 heartbeater.cc:344] Connected to a master server at 127.30.29.126:40363
I20260812 06:19:17.090479 31099 heartbeater.cc:461] Registering TS with master...
I20260812 06:19:17.090873 31099 heartbeater.cc:507] Master 127.30.29.126:40363 requested a full tablet report, sending...
I20260812 06:19:17.092190 30888 ts_manager.cc:194] Registered new tserver with Master: 03f2b67797b84b61bed9844afff218ca (127.30.29.65:32939)
I20260812 06:19:17.092280 30837 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.01164287s
I20260812 06:19:17.093562 30888 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:53444
I20260812 06:19:17.100983 30888 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:53460:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:19:17.115281 31043 tablet_service.cc:1511] Processing CreateTablet for tablet 69ea302dd84942b7b4e698785f73cf65 (DEFAULT_TABLE table=heavy-update-compaction-test [id=4ef9992b805843dda480ba78f9662cf5]), partition=
I20260812 06:19:17.115803 31043 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 69ea302dd84942b7b4e698785f73cf65. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:17.118208 31124 tablet_bootstrap.cc:492] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Bootstrap starting.
I20260812 06:19:17.119243 31124 tablet_bootstrap.cc:654] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:17.120281 31124 tablet_bootstrap.cc:492] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: No bootstrap required, opened a new log
I20260812 06:19:17.120366 31124 ts_tablet_manager.cc:1403] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:19:17.120703 31124 raft_consensus.cc:359] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "03f2b67797b84b61bed9844afff218ca" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 32939 } }
I20260812 06:19:17.120806 31124 raft_consensus.cc:385] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:17.120831 31124 raft_consensus.cc:740] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 03f2b67797b84b61bed9844afff218ca, State: Initialized, Role: FOLLOWER
I20260812 06:19:17.120975 31124 consensus_queue.cc:260] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [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: "03f2b67797b84b61bed9844afff218ca" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 32939 } }
I20260812 06:19:17.121064 31124 raft_consensus.cc:399] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:17.121091 31124 raft_consensus.cc:493] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:17.121127 31124 raft_consensus.cc:3060] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:17.121912 31124 raft_consensus.cc:515] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "03f2b67797b84b61bed9844afff218ca" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 32939 } }
I20260812 06:19:17.122040 31124 leader_election.cc:304] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [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: 03f2b67797b84b61bed9844afff218ca; no voters: 
I20260812 06:19:17.122279 31124 leader_election.cc:290] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:17.122428 31126 raft_consensus.cc:2804] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:17.122644 31124 ts_tablet_manager.cc:1434] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:19:17.122890 31099 heartbeater.cc:499] Master 127.30.29.126:40363 was elected leader, sending a full tablet report...
I20260812 06:19:17.122691 31126 raft_consensus.cc:697] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 1 LEADER]: Becoming Leader. State: Replica: 03f2b67797b84b61bed9844afff218ca, State: Running, Role: LEADER
I20260812 06:19:17.123283 31126 consensus_queue.cc:237] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [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: "03f2b67797b84b61bed9844afff218ca" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 32939 } }
I20260812 06:19:17.125866 30888 catalog_manager.cc:5719] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca reported cstate change: term changed from 0 to 1, leader changed from <none> to 03f2b67797b84b61bed9844afff218ca (127.30.29.65). New cstate: current_term: 1 leader_uuid: "03f2b67797b84b61bed9844afff218ca" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "03f2b67797b84b61bed9844afff218ca" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 32939 } health_report { overall_health: HEALTHY } } }
I20260812 06:19:17.189558 30837 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.054s	user 0.018s	sys 0.008s
I20260812 06:19:17.331132 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushMRSOp(69ea302dd84942b7b4e698785f73cf65): perf score=19.054940
I20260812 06:19:17.509181 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushMRSOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.178s	user 0.130s	sys 0.032s Metrics: {"bytes_written":12307490,"cfile_init":1,"compiler_manager_pool.queue_time_us":287,"delete_count":0,"dirs.queue_time_us":55,"dirs.run_cpu_time_us":206,"dirs.run_wall_time_us":770,"drs_written":1,"lbm_read_time_us":54,"lbm_reads_lt_1ms":4,"lbm_write_time_us":43891,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"thread_start_us":173,"threads_started":1,"update_count":1500}
I20260812 06:19:17.510471 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling LogGCOp(69ea302dd84942b7b4e698785f73cf65): free 20743831 bytes of WAL
I20260812 06:19:17.510808 31010 log_reader.cc:385] T 69ea302dd84942b7b4e698785f73cf65: removed 2 log segments from log reader
I20260812 06:19:17.510896 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000001 (ops 1-6)
I20260812 06:19:17.511004 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000002 (ops 7-11)
I20260812 06:19:17.515241 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: LogGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.005s	user 0.003s	sys 0.000s Metrics: {}
I20260812 06:19:17.515662 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65): 16411392 bytes on disk
I20260812 06:19:17.516300 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":59,"lbm_reads_lt_1ms":4}
I20260812 06:19:17.516772 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:17.532919 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.016s	user 0.006s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6380,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.533463 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:17.683295 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.150s	user 0.095s	sys 0.050s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":594,"lbm_read_time_us":7549,"lbm_reads_lt_1ms":464,"lbm_write_time_us":24031,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":768,"thread_start_us":311,"threads_started":5,"update_count":2000}
I20260812 06:19:17.684011 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=10.126437
I20260812 06:19:17.723261 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.039s	user 0.030s	sys 0.004s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":14902,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:17.723794 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:17.739341 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.015s	user 0.008s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5690,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.740007 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:17.878011 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.138s	user 0.107s	sys 0.030s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":706,"lbm_read_time_us":9015,"lbm_reads_lt_1ms":472,"lbm_write_time_us":26914,"lbm_writes_lt_1ms":443,"mutex_wait_us":21,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8960,"update_count":2000}
I20260812 06:19:17.878594 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=10.126437
I20260812 06:19:17.920122 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.041s	user 0.025s	sys 0.006s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":14378,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:17.920639 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:17.931898 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4044,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:17.932539 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:18.058772 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.126s	user 0.090s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":257,"lbm_read_time_us":9604,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25692,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":10752,"update_count":2000}
I20260812 06:19:18.059262 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=10.126437
I20260812 06:19:18.114579 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.055s	user 0.035s	sys 0.011s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":16513,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:18.115239 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:18.126308 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.011s	user 0.005s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4248,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:18.126776 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:18.267499 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.141s	user 0.082s	sys 0.058s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":547,"lbm_read_time_us":10869,"lbm_reads_lt_1ms":472,"lbm_write_time_us":23551,"lbm_writes_lt_1ms":443,"mutex_wait_us":48,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":17664,"update_count":2000}
I20260812 06:19:18.268208 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=10.126437
I20260812 06:19:18.312402 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.044s	user 0.019s	sys 0.019s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":17633,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:18.312906 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:18.323907 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4203,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:18.324568 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:18.455102 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.130s	user 0.104s	sys 0.026s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":985,"lbm_read_time_us":8779,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24054,"lbm_writes_lt_1ms":443,"mutex_wait_us":264,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":15872,"update_count":2000}
I20260812 06:19:18.455781 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=10.126437
I20260812 06:19:18.493090 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.037s	user 0.012s	sys 0.021s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15442,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:18.493767 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:18.510819 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.017s	user 0.006s	sys 0.009s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6308,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:18.511322 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:18.629225 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.118s	user 0.099s	sys 0.017s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672277,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":644,"lbm_read_time_us":7509,"lbm_reads_lt_1ms":472,"lbm_write_time_us":22167,"lbm_writes_lt_1ms":443,"mutex_wait_us":41,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2048,"update_count":2000}
I20260812 06:19:18.630802 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=10.126437
I20260812 06:19:18.663872 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.033s	user 0.023s	sys 0.007s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":14329,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:18.664348 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:18.678150 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.014s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5308,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:18.678619 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushMRSOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:18.709142 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushMRSOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.030s	user 0.028s	sys 0.000s Metrics: {"bytes_written":1193503,"cfile_init":1,"dirs.queue_time_us":68,"dirs.run_cpu_time_us":215,"dirs.run_wall_time_us":1373,"drs_written":1,"lbm_read_time_us":45,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1408,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:19:18.710039 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling LogGCOp(69ea302dd84942b7b4e698785f73cf65): free 111786307 bytes of WAL
I20260812 06:19:18.710292 31010 log_reader.cc:385] T 69ea302dd84942b7b4e698785f73cf65: removed 11 log segments from log reader
I20260812 06:19:18.710340 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000003 (ops 12-16)
I20260812 06:19:18.710371 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000004 (ops 17-21)
I20260812 06:19:18.710387 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000005 (ops 22-26)
I20260812 06:19:18.710449 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000006 (ops 27-31)
I20260812 06:19:18.710501 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000007 (ops 32-36)
I20260812 06:19:18.710520 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000008 (ops 37-40)
I20260812 06:19:18.710537 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000009 (ops 41-45)
I20260812 06:19:18.710578 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000010 (ops 46-50)
I20260812 06:19:18.710619 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000011 (ops 51-55)
I20260812 06:19:18.710637 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000012 (ops 56-60)
I20260812 06:19:18.710687 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000013 (ops 61-64)
I20260812 06:19:18.737608 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: LogGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.027s	user 0.000s	sys 0.027s Metrics: {}
I20260812 06:19:18.738062 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65): 463 bytes on disk
I20260812 06:19:18.738610 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":71,"lbm_reads_lt_1ms":4}
I20260812 06:19:18.739339 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=3.181125
I20260812 06:19:18.756673 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.017s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4882122,"delete_count":0,"lbm_write_time_us":6960,"lbm_writes_lt_1ms":122,"reinsert_count":0,"update_count":595}
I20260812 06:19:18.757122 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling LogGCOp(69ea302dd84942b7b4e698785f73cf65): free 8767118 bytes of WAL
I20260812 06:19:18.757335 31010 log_reader.cc:385] T 69ea302dd84942b7b4e698785f73cf65: removed 1 log segments from log reader
I20260812 06:19:18.757380 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000014 (ops 65-69)
I20260812 06:19:18.759160 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: LogGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.002s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:19:18.759460 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:18.769217 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.010s	user 0.006s	sys 0.001s Metrics: {"bytes_written":3323180,"delete_count":0,"lbm_write_time_us":3120,"lbm_writes_lt_1ms":84,"reinsert_count":0,"update_count":405}
I20260812 06:19:18.769678 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:18.937645 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.168s	user 0.119s	sys 0.043s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877324,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":251,"lbm_read_time_us":12826,"lbm_reads_lt_1ms":674,"lbm_write_time_us":31838,"lbm_writes_lt_1ms":643,"mutex_wait_us":43,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":2304,"thread_start_us":79,"threads_started":1,"update_count":3000}
I20260812 06:19:18.938148 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=14.095187
I20260812 06:19:18.987701 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.049s	user 0.028s	sys 0.012s Metrics: {"bytes_written":16409897,"delete_count":0,"lbm_write_time_us":18810,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:18.988288 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:19.004092 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.016s	user 0.013s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5798,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:19.004726 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:19.160516 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.156s	user 0.121s	sys 0.032s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774684,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":786,"lbm_read_time_us":10466,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30331,"lbm_writes_lt_1ms":543,"mutex_wait_us":272,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7936,"update_count":2500}
I20260812 06:19:19.161290 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=12.110812
I20260812 06:19:19.212014 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.050s	user 0.019s	sys 0.022s Metrics: {"bytes_written":13579240,"delete_count":0,"lbm_write_time_us":22150,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":333,"reinsert_count":0,"update_count":1655}
I20260812 06:19:19.212545 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.196750
I20260812 06:19:19.233182 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.020s	user 0.008s	sys 0.000s Metrics: {"bytes_written":2830880,"delete_count":0,"lbm_write_time_us":3878,"lbm_writes_lt_1ms":72,"reinsert_count":0,"update_count":345}
I20260812 06:19:19.233702 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:19.243999 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3883,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:19.244524 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:19.415153 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.170s	user 0.133s	sys 0.035s Metrics: {"cfile_cache_miss":533,"cfile_cache_miss_bytes":24774779,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":686,"lbm_read_time_us":13947,"lbm_reads_lt_1ms":573,"lbm_write_time_us":28202,"lbm_writes_lt_1ms":543,"mutex_wait_us":22,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":7808,"update_count":2500}
I20260812 06:19:19.415850 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=14.095187
I20260812 06:19:19.471700 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.056s	user 0.025s	sys 0.019s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":20694,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:19.472275 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:19.483068 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4057,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:19.483762 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:19.658967 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.175s	user 0.138s	sys 0.035s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774686,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":437,"lbm_read_time_us":11683,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27611,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1664,"update_count":2500}
I20260812 06:19:19.659586 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=14.095187
I20260812 06:19:19.722146 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.062s	user 0.028s	sys 0.028s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":21121,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:19.722733 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:19.733345 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4065,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:19.733880 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:19.915419 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.181s	user 0.129s	sys 0.050s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774689,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":710,"lbm_read_time_us":13071,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29481,"lbm_writes_lt_1ms":543,"mutex_wait_us":298,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1408,"update_count":2500}
I20260812 06:19:19.915930 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=11.118625
I20260812 06:19:19.955693 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.040s	user 0.024s	sys 0.012s Metrics: {"bytes_written":12717736,"delete_count":0,"lbm_write_time_us":16473,"lbm_writes_lt_1ms":313,"reinsert_count":0,"update_count":1550}
I20260812 06:19:19.956302 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:19.979007 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.022s	user 0.013s	sys 0.007s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":5185,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:19.980171 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:20.131203 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.151s	user 0.100s	sys 0.050s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672269,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":302,"lbm_read_time_us":10745,"lbm_reads_lt_1ms":464,"lbm_write_time_us":25908,"lbm_writes_lt_1ms":443,"mutex_wait_us":28,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":23808,"update_count":2000}
I20260812 06:19:20.131873 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=10.126437
I20260812 06:19:20.170869 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.039s	user 0.033s	sys 0.004s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":16429,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:20.171471 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:20.198859 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.027s	user 0.008s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5854,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.199393 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:20.216411 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.017s	user 0.008s	sys 0.006s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6356,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.217275 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushMRSOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:20.256202 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushMRSOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.039s	user 0.031s	sys 0.003s Metrics: {"bytes_written":1234479,"cfile_init":1,"dirs.queue_time_us":76,"dirs.run_cpu_time_us":196,"dirs.run_wall_time_us":1418,"drs_written":1,"lbm_read_time_us":75,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2610,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":30,"spinlock_wait_cycles":3840}
I20260812 06:19:20.257007 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling LogGCOp(69ea302dd84942b7b4e698785f73cf65): free 120553345 bytes of WAL
I20260812 06:19:20.257301 31010 log_reader.cc:385] T 69ea302dd84942b7b4e698785f73cf65: removed 12 log segments from log reader
I20260812 06:19:20.257356 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000015 (ops 70-74)
I20260812 06:19:20.257421 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000016 (ops 75-79)
I20260812 06:19:20.257472 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000017 (ops 80-84)
I20260812 06:19:20.257535 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000018 (ops 85-88)
I20260812 06:19:20.257579 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000019 (ops 89-93)
I20260812 06:19:20.257620 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000020 (ops 94-98)
I20260812 06:19:20.257668 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000021 (ops 99-102)
I20260812 06:19:20.257735 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000022 (ops 103-107)
I20260812 06:19:20.257782 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000023 (ops 108-112)
I20260812 06:19:20.257826 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000024 (ops 113-117)
I20260812 06:19:20.257870 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000025 (ops 118-122)
I20260812 06:19:20.257917 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000026 (ops 123-127)
I20260812 06:19:20.284147 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: LogGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.027s	user 0.001s	sys 0.023s Metrics: {}
I20260812 06:19:20.284646 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65): 472 bytes on disk
I20260812 06:19:20.285229 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":83,"lbm_reads_lt_1ms":4}
I20260812 06:19:20.285890 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:20.308564 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.022s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7258,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.309082 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:20.330395 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.021s	user 0.007s	sys 0.014s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4558,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.331125 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:20.571019 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.240s	user 0.138s	sys 0.091s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":32979870,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":1069,"lbm_read_time_us":17516,"lbm_reads_lt_1ms":775,"lbm_write_time_us":38495,"lbm_writes_lt_1ms":743,"mutex_wait_us":271,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":8448,"thread_start_us":97,"threads_started":1,"update_count":3500}
I20260812 06:19:20.571786 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=18.063937
I20260812 06:19:20.642392 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.070s	user 0.037s	sys 0.024s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":27804,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:20.642935 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:20.653975 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102660,"delete_count":0,"lbm_write_time_us":4159,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.654706 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:20.861143 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.206s	user 0.154s	sys 0.052s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877103,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":325,"lbm_read_time_us":13733,"lbm_reads_lt_1ms":672,"lbm_write_time_us":36642,"lbm_writes_lt_1ms":643,"mutex_wait_us":49,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3328,"update_count":3000}
I20260812 06:19:20.862010 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=14.095187
I20260812 06:19:20.914202 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.052s	user 0.035s	sys 0.017s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":22387,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:20.915138 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:20.928345 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.013s	user 0.004s	sys 0.007s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5157,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:20.928846 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:21.191488 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.262s	user 0.167s	sys 0.091s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":629,"lbm_read_time_us":15349,"lbm_reads_lt_1ms":572,"lbm_write_time_us":53458,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2500}
I20260812 06:19:21.192665 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=15.087375
I20260812 06:19:21.297752 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.105s	user 0.064s	sys 0.024s Metrics: {"bytes_written":16820143,"delete_count":0,"lbm_write_time_us":43056,"lbm_writes_1-10_ms":3,"lbm_writes_lt_1ms":410,"reinsert_count":0,"update_count":2050}
I20260812 06:19:21.298556 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=6.157687
I20260812 06:19:21.335803 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.037s	user 0.018s	sys 0.014s Metrics: {"bytes_written":7794836,"delete_count":0,"lbm_write_time_us":14060,"lbm_writes_lt_1ms":193,"reinsert_count":0,"update_count":950}
I20260812 06:19:21.336596 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:21.717005 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.380s	user 0.225s	sys 0.152s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877101,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1064,"lbm_read_time_us":25992,"lbm_reads_lt_1ms":664,"lbm_write_time_us":76864,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3072,"thread_start_us":557,"threads_started":5,"update_count":3000}
I20260812 06:19:21.718546 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=15.087375
I20260812 06:19:21.844056 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.125s	user 0.064s	sys 0.040s Metrics: {"bytes_written":17148341,"delete_count":0,"lbm_write_time_us":50964,"lbm_writes_1-10_ms":4,"lbm_writes_lt_1ms":417,"reinsert_count":0,"update_count":2090}
I20260812 06:19:21.844906 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=6.157687
I20260812 06:19:21.891201 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.046s	user 0.030s	sys 0.008s Metrics: {"bytes_written":7466645,"delete_count":0,"lbm_write_time_us":16856,"lbm_writes_lt_1ms":185,"reinsert_count":0,"update_count":910}
I20260812 06:19:21.892053 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:22.237604 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.345s	user 0.234s	sys 0.105s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877108,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":955,"lbm_read_time_us":24082,"lbm_reads_lt_1ms":664,"lbm_write_time_us":62632,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"thread_start_us":501,"threads_started":5,"update_count":3000}
I20260812 06:19:22.238633 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=18.063937
I20260812 06:19:22.382035 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.143s	user 0.097s	sys 0.019s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":53606,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:22.382946 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:22.402966 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.020s	user 0.010s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7476,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:22.403653 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushMRSOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:22.461835 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushMRSOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.058s	user 0.046s	sys 0.004s Metrics: {"bytes_written":1275447,"cfile_init":1,"dirs.queue_time_us":125,"dirs.run_cpu_time_us":461,"dirs.run_wall_time_us":1571,"drs_written":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4,"lbm_write_time_us":3828,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:19:22.463061 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling LogGCOp(69ea302dd84942b7b4e698785f73cf65): free 129320835 bytes of WAL
I20260812 06:19:22.463459 31010 log_reader.cc:385] T 69ea302dd84942b7b4e698785f73cf65: removed 13 log segments from log reader
I20260812 06:19:22.463531 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000027 (ops 128-132)
I20260812 06:19:22.463613 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000028 (ops 133-137)
I20260812 06:19:22.463685 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000029 (ops 138-142)
I20260812 06:19:22.463742 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000030 (ops 143-146)
I20260812 06:19:22.463811 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000031 (ops 147-151)
I20260812 06:19:22.463888 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000032 (ops 152-156)
I20260812 06:19:22.463956 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000033 (ops 157-161)
I20260812 06:19:22.463979 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000034 (ops 162-166)
I20260812 06:19:22.464084 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000035 (ops 167-171)
I20260812 06:19:22.464174 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000036 (ops 172-176)
I20260812 06:19:22.464318 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000037 (ops 177-180)
I20260812 06:19:22.464409 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000038 (ops 181-185)
I20260812 06:19:22.464488 31010 log.cc:1079] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/69ea302dd84942b7b4e698785f73cf65/wal-000000039 (ops 186-190)
I20260812 06:19:22.512617 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: LogGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.049s	user 0.003s	sys 0.043s Metrics: {}
I20260812 06:19:22.514685 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=3.181125
I20260812 06:19:22.554646 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.040s	user 0.022s	sys 0.008s Metrics: {"bytes_written":4389828,"delete_count":0,"lbm_write_time_us":13048,"lbm_writes_lt_1ms":110,"reinsert_count":0,"update_count":535}
I20260812 06:19:22.555541 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=2.188937
I20260812 06:19:22.574196 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.018s	user 0.014s	sys 0.004s Metrics: {"bytes_written":3815484,"delete_count":0,"lbm_write_time_us":6998,"lbm_writes_lt_1ms":96,"reinsert_count":0,"update_count":465}
I20260812 06:19:22.575636 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65): perf score=1.000000
I20260812 06:19:22.808490 30837 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.619s	user 2.004s	sys 0.201s
I20260812 06:19:22.871284 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: MajorDeltaCompactionOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.295s	user 0.193s	sys 0.100s Metrics: {"cfile_cache_miss":834,"cfile_cache_miss_bytes":37082160,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"lbm_read_time_us":33678,"lbm_reads_lt_1ms":870,"lbm_write_time_us":48845,"lbm_writes_lt_1ms":843,"peak_mem_usage":100395616,"reinsert_count":0,"spinlock_wait_cycles":896,"update_count":4000}
I20260812 06:19:22.871797 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65): 483 bytes on disk
I20260812 06:19:22.872221 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: UndoDeltaBlockGCOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":45,"lbm_reads_lt_1ms":4}
I20260812 06:19:22.872752 31100 maintenance_manager.cc:419] P 03f2b67797b84b61bed9844afff218ca: Scheduling FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65): perf score=14.095187
I20260812 06:19:22.906358 30837 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.097s	user 0.003s	sys 0.000s
I20260812 06:19:22.907156 30837 tablet_server.cc:179] TabletServer@127.30.29.65:0 shutting down...
I20260812 06:19:22.916491 31010 maintenance_manager.cc:643] P 03f2b67797b84b61bed9844afff218ca: FlushDeltaMemStoresOp(69ea302dd84942b7b4e698785f73cf65) complete. Timing: real 0.044s	user 0.020s	sys 0.019s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":19060,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:22.917095 30837 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:19:22.917550 30837 tablet_replica.cc:333] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca: stopping tablet replica
I20260812 06:19:22.917814 30837 raft_consensus.cc:2243] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:22.918089 30837 raft_consensus.cc:2272] T 69ea302dd84942b7b4e698785f73cf65 P 03f2b67797b84b61bed9844afff218ca [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:22.933280 30837 tablet_server.cc:196] TabletServer@127.30.29.65:0 shutdown complete.
I20260812 06:19:22.960778 30837 master.cc:562] Master@127.30.29.126:40363 shutting down...
I20260812 06:19:22.964995 30837 raft_consensus.cc:2243] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:22.965225 30837 raft_consensus.cc:2272] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:22.965325 30837 tablet_replica.cc:333] T 00000000000000000000000000000000 P ae561b70e6464316ba25d6237951eaee: stopping tablet replica
I20260812 06:19:22.978101 30837 master.cc:584] Master@127.30.29.126:40363 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (6223 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:19:23.067651 30837 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.30.29.126:44247
I20260812 06:19:23.068078 30837 file_cache.cc:504] Constructed file cache file cache with capacity 419430
I20260812 06:19:23.070576 30837 server_base.cc:1061] running on GCE node
W20260812 06:19:23.070607 31187 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:19:23.070586 31188 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:19:23.070693 31193 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:19:23.070996 30837 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:23.071048 30837 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:19:23.071066 30837 hybrid_clock.cc:648] HybridClock initialized: now 1786515563071067 us; error 0 us; skew 500 ppm
I20260812 06:19:23.072005 30837 webserver.cc:533] Webserver started at http://127.30.29.126:37007/ using document root <none> and password file <none>
I20260812 06:19:23.072201 30837 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:23.072270 30837 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:23.072359 30837 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:23.072791 30837 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/master-0-root/instance:
uuid: "d45c384fb41d4d4cbe0b423bf223c105"
format_stamp: "Formatted at 2026-08-12 06:19:23 on dist-test-slave-gsp7"
I20260812 06:19:23.074473 30837 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:19:23.075431 31209 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:23.075691 30837 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:23.075783 30837 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/master-0-root
uuid: "d45c384fb41d4d4cbe0b423bf223c105"
format_stamp: "Formatted at 2026-08-12 06:19:23 on dist-test-slave-gsp7"
I20260812 06:19:23.075875 30837 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/master-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:19:23.091053 30837 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:23.091540 30837 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:23.095799 30837 rpc_server.cc:307] RPC server started. Bound to: 127.30.29.126:44247
I20260812 06:19:23.100445 31291 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.29.126:44247 every 8 connection(s)
I20260812 06:19:23.102651 31292 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:23.117138 31292 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105: Bootstrap starting.
I20260812 06:19:23.118098 31292 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:23.119218 31292 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105: No bootstrap required, opened a new log
I20260812 06:19:23.119630 31292 raft_consensus.cc:359] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d45c384fb41d4d4cbe0b423bf223c105" member_type: VOTER }
I20260812 06:19:23.119752 31292 raft_consensus.cc:385] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:23.119815 31292 raft_consensus.cc:740] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d45c384fb41d4d4cbe0b423bf223c105, State: Initialized, Role: FOLLOWER
I20260812 06:19:23.119999 31292 consensus_queue.cc:260] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [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: "d45c384fb41d4d4cbe0b423bf223c105" member_type: VOTER }
I20260812 06:19:23.120101 31292 raft_consensus.cc:399] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:23.120146 31292 raft_consensus.cc:493] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:23.120199 31292 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:23.120921 31292 raft_consensus.cc:515] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d45c384fb41d4d4cbe0b423bf223c105" member_type: VOTER }
I20260812 06:19:23.121112 31292 leader_election.cc:304] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [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: d45c384fb41d4d4cbe0b423bf223c105; no voters: 
I20260812 06:19:23.121317 31292 leader_election.cc:290] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:23.121528 31295 raft_consensus.cc:2804] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:23.121775 31295 raft_consensus.cc:697] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 1 LEADER]: Becoming Leader. State: Replica: d45c384fb41d4d4cbe0b423bf223c105, State: Running, Role: LEADER
I20260812 06:19:23.121873 31292 sys_catalog.cc:565] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:19:23.121948 31295 consensus_queue.cc:237] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [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: "d45c384fb41d4d4cbe0b423bf223c105" member_type: VOTER }
I20260812 06:19:23.122413 31296 sys_catalog.cc:455] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d45c384fb41d4d4cbe0b423bf223c105" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d45c384fb41d4d4cbe0b423bf223c105" member_type: VOTER } }
I20260812 06:19:23.122454 31297 sys_catalog.cc:455] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [sys.catalog]: SysCatalogTable state changed. Reason: New leader d45c384fb41d4d4cbe0b423bf223c105. Latest consensus state: current_term: 1 leader_uuid: "d45c384fb41d4d4cbe0b423bf223c105" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d45c384fb41d4d4cbe0b423bf223c105" member_type: VOTER } }
I20260812 06:19:23.122525 31296 sys_catalog.cc:458] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:23.122534 31297 sys_catalog.cc:458] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [sys.catalog]: This master's current role is: LEADER
I20260812 06:19:23.122794 31300 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:19:23.123657 31300 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:19:23.123868 30837 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:19:23.125527 31300 catalog_manager.cc:1383] Generated new cluster ID: 772d010b6e4046a7b6775f7e9bcf1fe4
I20260812 06:19:23.125583 31300 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:19:23.130594 31300 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:19:23.131117 31300 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:19:23.135970 31300 catalog_manager.cc:6092] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105: Generated new TSK 0
I20260812 06:19:23.136142 31300 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:19:23.140203 30837 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:19:23.142145 31320 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:19:23.142217 31325 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
W20260812 06:19:23.142292 31321 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
I20260812 06:19:23.142395 30837 server_base.cc:1061] running on GCE node
I20260812 06:19:23.142674 30837 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:19:23.142719 30837 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
I20260812 06:19:23.142771 30837 hybrid_clock.cc:648] HybridClock initialized: now 1786515563142770 us; error 0 us; skew 500 ppm
I20260812 06:19:23.143743 30837 webserver.cc:533] Webserver started at http://127.30.29.65:33459/ using document root <none> and password file <none>
I20260812 06:19:23.143944 30837 fs_manager.cc:362] Metadata directory not provided
I20260812 06:19:23.144023 30837 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:19:23.144117 30837 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:19:23.144591 30837 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/instance:
uuid: "e7888b771de544c5b5afbc733d3fe431"
format_stamp: "Formatted at 2026-08-12 06:19:23 on dist-test-slave-gsp7"
I20260812 06:19:23.146371 30837 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:19:23.147356 31332 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:23.147625 30837 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:19:23.147701 30837 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root
uuid: "e7888b771de544c5b5afbc733d3fe431"
format_stamp: "Formatted at 2026-08-12 06:19:23 on dist-test-slave-gsp7"
I20260812 06:19:23.147759 30837 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/data
Total live blocks: 0
Total live bytes: 0
Total live bytes (after alignment): 0
Total number of LBM containers: 0 (0 full)
Did not check for missing blocks
Did not check for orphaned blocks
Total full LBM containers with extra space: 0 (0 repaired)
Total full LBM container extra space in bytes: 0 (0 repaired)
Total incomplete LBM containers: 0 (0 repaired)
Total LBM partial records: 0 (0 repaired)
Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
I20260812 06:19:23.161224 30837 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:19:23.161695 30837 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:19:23.161979 30837 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:19:23.162490 30837 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:19:23.162529 30837 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:23.162593 30837 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:19:23.162635 30837 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:19:23.167068 30837 rpc_server.cc:307] RPC server started. Bound to: 127.30.29.65:37921
I20260812 06:19:23.167128 31440 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.29.65:37921 every 8 connection(s)
I20260812 06:19:23.175859 31441 heartbeater.cc:344] Connected to a master server at 127.30.29.126:44247
I20260812 06:19:23.176023 31441 heartbeater.cc:461] Registering TS with master...
I20260812 06:19:23.176311 31441 heartbeater.cc:507] Master 127.30.29.126:44247 requested a full tablet report, sending...
I20260812 06:19:23.177057 31234 ts_manager.cc:194] Registered new tserver with Master: e7888b771de544c5b5afbc733d3fe431 (127.30.29.65:37921)
I20260812 06:19:23.177551 30837 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.010032302s
I20260812 06:19:23.177929 31234 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:60814
I20260812 06:19:23.184897 31234 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:60816:
name: "heavy-update-compaction-test"
schema {
  columns {
    name: "key"
    type: INT64
    is_key: true
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_a"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_b"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_c"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_d"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
  columns {
    name: "val_e"
    type: STRING
    is_key: false
    is_nullable: false
    encoding: AUTO_ENCODING
    compression: DEFAULT_COMPRESSION
    cfile_block_size: 0
    immutable: false
  }
}
num_replicas: 1
split_rows_range_bounds {
}
partition_schema {
  range_schema {
  }
}
I20260812 06:19:23.194274 31388 tablet_service.cc:1511] Processing CreateTablet for tablet 6b0934e0033349998e78dd4a7bda5851 (DEFAULT_TABLE table=heavy-update-compaction-test [id=c4eb82c322204f9ca1e35086217bd36f]), partition=
I20260812 06:19:23.194605 31388 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 6b0934e0033349998e78dd4a7bda5851. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:19:23.196705 31463 tablet_bootstrap.cc:492] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Bootstrap starting.
I20260812 06:19:23.197672 31463 tablet_bootstrap.cc:654] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Neither blocks nor log segments found. Creating new log.
I20260812 06:19:23.198896 31463 tablet_bootstrap.cc:492] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: No bootstrap required, opened a new log
I20260812 06:19:23.199028 31463 ts_tablet_manager.cc:1403] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:19:23.199572 31463 raft_consensus.cc:359] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "e7888b771de544c5b5afbc733d3fe431" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 37921 } }
I20260812 06:19:23.199687 31463 raft_consensus.cc:385] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:19:23.199757 31463 raft_consensus.cc:740] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: e7888b771de544c5b5afbc733d3fe431, State: Initialized, Role: FOLLOWER
I20260812 06:19:23.199910 31463 consensus_queue.cc:260] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [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: "e7888b771de544c5b5afbc733d3fe431" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 37921 } }
I20260812 06:19:23.199986 31463 raft_consensus.cc:399] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:19:23.200011 31463 raft_consensus.cc:493] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:19:23.200104 31463 raft_consensus.cc:3060] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:19:23.200858 31463 raft_consensus.cc:515] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "e7888b771de544c5b5afbc733d3fe431" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 37921 } }
I20260812 06:19:23.201004 31463 leader_election.cc:304] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [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: e7888b771de544c5b5afbc733d3fe431; no voters: 
I20260812 06:19:23.201220 31463 leader_election.cc:290] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:19:23.201399 31467 raft_consensus.cc:2804] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:19:23.201602 31441 heartbeater.cc:499] Master 127.30.29.126:44247 was elected leader, sending a full tablet report...
I20260812 06:19:23.201615 31463 ts_tablet_manager.cc:1434] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:19:23.201669 31467 raft_consensus.cc:697] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 1 LEADER]: Becoming Leader. State: Replica: e7888b771de544c5b5afbc733d3fe431, State: Running, Role: LEADER
I20260812 06:19:23.201856 31467 consensus_queue.cc:237] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [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: "e7888b771de544c5b5afbc733d3fe431" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 37921 } }
I20260812 06:19:23.203269 31234 catalog_manager.cc:5719] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 reported cstate change: term changed from 0 to 1, leader changed from <none> to e7888b771de544c5b5afbc733d3fe431 (127.30.29.65). New cstate: current_term: 1 leader_uuid: "e7888b771de544c5b5afbc733d3fe431" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "e7888b771de544c5b5afbc733d3fe431" member_type: VOTER last_known_addr { host: "127.30.29.65" port: 37921 } health_report { overall_health: HEALTHY } } }
I20260812 06:19:23.266983 30837 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.057s	user 0.021s	sys 0.004s
I20260812 06:19:23.418130 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushMRSOp(6b0934e0033349998e78dd4a7bda5851): perf score=19.054940
I20260812 06:19:23.572702 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushMRSOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.154s	user 0.093s	sys 0.057s Metrics: {"bytes_written":12307491,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":89,"dirs.run_cpu_time_us":201,"dirs.run_wall_time_us":713,"drs_written":1,"lbm_read_time_us":51,"lbm_reads_lt_1ms":4,"lbm_write_time_us":40565,"lbm_writes_lt_1ms":757,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"update_count":1500}
I20260812 06:19:23.573374 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling LogGCOp(6b0934e0033349998e78dd4a7bda5851): free 20743880 bytes of WAL
I20260812 06:19:23.573635 31338 log_reader.cc:385] T 6b0934e0033349998e78dd4a7bda5851: removed 2 log segments from log reader
I20260812 06:19:23.573681 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000001 (ops 1-6)
I20260812 06:19:23.573740 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000002 (ops 7-11)
I20260812 06:19:23.578097 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: LogGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.005s	user 0.000s	sys 0.004s Metrics: {}
I20260812 06:19:23.578471 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:23.590514 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.012s	user 0.010s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4426,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:23.591089 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:23.740028 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.149s	user 0.112s	sys 0.035s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":506,"lbm_read_time_us":8979,"lbm_reads_lt_1ms":468,"lbm_write_time_us":27118,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":3584,"thread_start_us":461,"threads_started":5,"update_count":2000}
I20260812 06:19:23.740836 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=10.126437
I20260812 06:19:23.793157 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.052s	user 0.023s	sys 0.016s Metrics: {"bytes_written":12307491,"delete_count":0,"lbm_write_time_us":17744,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:19:23.793778 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:23.813009 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.019s	user 0.012s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":7086,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:23.813731 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851): 16411392 bytes on disk
I20260812 06:19:23.814345 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":94,"lbm_reads_lt_1ms":4}
I20260812 06:19:23.814935 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:24.037782 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.223s	user 0.180s	sys 0.040s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672278,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":3343,"lbm_read_time_us":13650,"lbm_reads_lt_1ms":472,"lbm_write_time_us":32894,"lbm_writes_lt_1ms":443,"mutex_wait_us":3215,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":2304,"update_count":2000}
I20260812 06:19:24.038487 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=18.063937
I20260812 06:19:24.092614 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.054s	user 0.022s	sys 0.029s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":24480,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:24.093156 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:24.103643 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3949,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:24.104105 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:24.313529 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.209s	user 0.158s	sys 0.051s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877102,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":252,"lbm_read_time_us":14509,"lbm_reads_lt_1ms":672,"lbm_write_time_us":34343,"lbm_writes_lt_1ms":643,"mutex_wait_us":2,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":7552,"update_count":3000}
I20260812 06:19:24.314260 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:24.363201 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.049s	user 0.027s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19866,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:24.363718 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:24.530870 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.167s	user 0.111s	sys 0.048s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672157,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":1212,"lbm_read_time_us":11402,"lbm_reads_lt_1ms":463,"lbm_write_time_us":25103,"lbm_writes_lt_1ms":443,"mutex_wait_us":29,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":24576,"update_count":2000}
I20260812 06:19:24.531463 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:24.578240 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.047s	user 0.028s	sys 0.016s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19555,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:24.578780 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:24.590423 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4131,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:24.590932 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:24.773087 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.182s	user 0.134s	sys 0.040s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1514,"lbm_read_time_us":9608,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28920,"lbm_writes_lt_1ms":543,"mutex_wait_us":492,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2048,"update_count":2500}
I20260812 06:19:24.773676 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:24.821571 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.048s	user 0.027s	sys 0.016s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":19042,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:24.822083 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:24.834014 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.012s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4304,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:24.834522 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushMRSOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:24.864722 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushMRSOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.030s	user 0.028s	sys 0.000s Metrics: {"bytes_written":1152508,"cfile_init":1,"dirs.queue_time_us":98,"dirs.run_cpu_time_us":265,"dirs.run_wall_time_us":1166,"drs_written":1,"lbm_read_time_us":42,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2032,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:19:24.865347 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling LogGCOp(6b0934e0033349998e78dd4a7bda5851): free 112239310 bytes of WAL
I20260812 06:19:24.865614 31338 log_reader.cc:385] T 6b0934e0033349998e78dd4a7bda5851: removed 11 log segments from log reader
I20260812 06:19:24.865662 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000003 (ops 12-16)
I20260812 06:19:24.865692 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000004 (ops 17-20)
I20260812 06:19:24.865756 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000005 (ops 21-25)
I20260812 06:19:24.865800 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000006 (ops 26-30)
I20260812 06:19:24.865839 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000007 (ops 31-35)
I20260812 06:19:24.865876 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000008 (ops 36-40)
I20260812 06:19:24.865921 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000009 (ops 41-45)
I20260812 06:19:24.865959 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000010 (ops 46-50)
I20260812 06:19:24.865995 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000011 (ops 51-55)
I20260812 06:19:24.866034 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000012 (ops 56-60)
I20260812 06:19:24.866070 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000013 (ops 61-65)
I20260812 06:19:24.890403 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: LogGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.025s	user 0.001s	sys 0.023s Metrics: {}
I20260812 06:19:24.896690 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851): 448 bytes on disk
I20260812 06:19:24.897183 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":72,"lbm_reads_lt_1ms":4}
I20260812 06:19:24.897926 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:24.920504 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.022s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6671,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:24.920960 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:24.932060 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.011s	user 0.002s	sys 0.007s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4341,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:24.932533 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:25.178953 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.246s	user 0.170s	sys 0.075s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979749,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1324,"lbm_read_time_us":16978,"lbm_reads_lt_1ms":774,"lbm_write_time_us":42678,"lbm_writes_lt_1ms":743,"mutex_wait_us":411,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":21760,"thread_start_us":93,"threads_started":1,"update_count":3500}
I20260812 06:19:25.179733 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=18.063937
I20260812 06:19:25.255573 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.076s	user 0.038s	sys 0.016s Metrics: {"bytes_written":20512316,"delete_count":0,"lbm_write_time_us":30227,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":502,"reinsert_count":0,"update_count":2500}
I20260812 06:19:25.256072 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:25.268147 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.012s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3914,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:25.268702 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:25.481909 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.213s	user 0.155s	sys 0.055s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877103,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":3764,"lbm_read_time_us":15213,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32405,"lbm_writes_lt_1ms":643,"mutex_wait_us":39,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":28288,"update_count":3000}
I20260812 06:19:25.483704 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=15.087375
I20260812 06:19:25.530107 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.045s	user 0.021s	sys 0.023s Metrics: {"bytes_written":16779118,"delete_count":0,"lbm_write_time_us":20239,"lbm_writes_lt_1ms":412,"reinsert_count":0,"update_count":2045}
I20260812 06:19:25.530697 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:25.546418 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.016s	user 0.005s	sys 0.009s Metrics: {"bytes_written":3733434,"delete_count":0,"lbm_write_time_us":6166,"lbm_writes_lt_1ms":94,"reinsert_count":0,"update_count":455}
I20260812 06:19:25.546947 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:25.744779 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.198s	user 0.130s	sys 0.062s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774680,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":862,"lbm_read_time_us":12340,"lbm_reads_lt_1ms":564,"lbm_write_time_us":32782,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":2500}
I20260812 06:19:25.745437 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:25.821568 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.076s	user 0.036s	sys 0.025s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":26427,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:25.822103 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:25.833451 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4325,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:25.834185 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:26.021696 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.186s	user 0.128s	sys 0.055s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":637,"lbm_read_time_us":13904,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30964,"lbm_writes_lt_1ms":543,"mutex_wait_us":31,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:19:26.023051 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:26.075461 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.052s	user 0.031s	sys 0.019s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":18142,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:26.076078 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:26.087402 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.011s	user 0.006s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4457,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:26.087888 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:26.277504 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.189s	user 0.134s	sys 0.044s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":582,"lbm_read_time_us":12528,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30209,"lbm_writes_lt_1ms":543,"mutex_wait_us":45,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3072,"update_count":2500}
I20260812 06:19:26.278065 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:26.338227 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.060s	user 0.028s	sys 0.028s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":21509,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:26.338886 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:26.349874 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.011s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4185,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:26.350448 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushMRSOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:26.394655 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushMRSOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.044s	user 0.033s	sys 0.000s Metrics: {"bytes_written":1152508,"cfile_init":1,"dirs.queue_time_us":69,"dirs.run_cpu_time_us":208,"dirs.run_wall_time_us":1310,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1456,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:19:26.395401 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling LogGCOp(6b0934e0033349998e78dd4a7bda5851): free 115943184 bytes of WAL
I20260812 06:19:26.395668 31338 log_reader.cc:385] T 6b0934e0033349998e78dd4a7bda5851: removed 11 log segments from log reader
I20260812 06:19:26.395715 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000014 (ops 66-70)
I20260812 06:19:26.395745 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000015 (ops 71-75)
I20260812 06:19:26.395807 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000016 (ops 76-80)
I20260812 06:19:26.395851 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000017 (ops 81-85)
I20260812 06:19:26.395896 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000018 (ops 86-90)
I20260812 06:19:26.395964 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000019 (ops 91-95)
I20260812 06:19:26.396009 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000020 (ops 96-100)
I20260812 06:19:26.396047 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000021 (ops 101-105)
I20260812 06:19:26.396085 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000022 (ops 106-110)
I20260812 06:19:26.396123 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000023 (ops 111-115)
I20260812 06:19:26.396160 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000024 (ops 116-120)
I20260812 06:19:26.420663 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: LogGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.025s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:19:26.421214 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851): 447 bytes on disk
I20260812 06:19:26.421816 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":77,"lbm_reads_lt_1ms":4}
I20260812 06:19:26.422588 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=3.181125
I20260812 06:19:26.444813 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.022s	user 0.015s	sys 0.000s Metrics: {"bytes_written":4348804,"delete_count":0,"lbm_write_time_us":6886,"lbm_writes_lt_1ms":109,"reinsert_count":0,"update_count":530}
I20260812 06:19:26.445283 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:26.455654 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":3856508,"delete_count":0,"lbm_write_time_us":3766,"lbm_writes_lt_1ms":97,"reinsert_count":0,"update_count":470}
I20260812 06:19:26.456122 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:26.697191 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.241s	user 0.150s	sys 0.087s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979746,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":1109,"lbm_read_time_us":17319,"lbm_reads_lt_1ms":774,"lbm_write_time_us":42099,"lbm_writes_lt_1ms":743,"mutex_wait_us":222,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":16768,"thread_start_us":83,"threads_started":1,"update_count":3500}
I20260812 06:19:26.698030 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=18.063937
I20260812 06:19:26.768721 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.070s	user 0.041s	sys 0.020s Metrics: {"bytes_written":20512314,"delete_count":0,"lbm_write_time_us":29474,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:19:26.769357 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:26.785949 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.016s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5936,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:26.786520 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:26.990593 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.204s	user 0.129s	sys 0.072s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877101,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":641,"lbm_read_time_us":14158,"lbm_reads_lt_1ms":672,"lbm_write_time_us":34794,"lbm_writes_lt_1ms":643,"mutex_wait_us":2360,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":12160,"update_count":3000}
I20260812 06:19:26.993002 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:27.037469 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.044s	user 0.027s	sys 0.013s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":20131,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:27.038093 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:27.055723 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.017s	user 0.010s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6898,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:27.056386 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:27.224251 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.168s	user 0.124s	sys 0.040s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774690,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":1175,"lbm_read_time_us":13118,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33021,"lbm_writes_lt_1ms":543,"mutex_wait_us":399,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":17408,"update_count":2500}
I20260812 06:19:27.225021 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:27.278081 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.053s	user 0.037s	sys 0.011s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21729,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:27.278594 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:27.434978 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.156s	user 0.111s	sys 0.040s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672157,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":827,"lbm_read_time_us":9686,"lbm_reads_lt_1ms":463,"lbm_write_time_us":25961,"lbm_writes_lt_1ms":443,"mutex_wait_us":338,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:19:27.435729 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:27.484579 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.049s	user 0.028s	sys 0.015s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":21278,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:27.485170 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:27.497798 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.012s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":4174,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:27.498560 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:27.676447 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.178s	user 0.117s	sys 0.054s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":229,"lbm_read_time_us":12109,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28904,"lbm_writes_lt_1ms":543,"mutex_wait_us":48,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13824,"update_count":2500}
I20260812 06:19:27.677104 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=14.095187
I20260812 06:19:27.732647 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.055s	user 0.035s	sys 0.011s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":22625,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:19:27.733168 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:27.745607 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.012s	user 0.009s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4322,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:27.746254 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:27.914422 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.168s	user 0.130s	sys 0.036s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774692,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":242,"lbm_read_time_us":11510,"lbm_reads_lt_1ms":572,"lbm_write_time_us":33509,"lbm_writes_lt_1ms":543,"mutex_wait_us":63,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":23680,"update_count":2500}
I20260812 06:19:27.915199 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=11.118625
I20260812 06:19:27.964434 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.049s	user 0.030s	sys 0.012s Metrics: {"bytes_written":12717735,"delete_count":0,"lbm_write_time_us":19876,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":312,"reinsert_count":0,"update_count":1550}
I20260812 06:19:27.964989 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:27.983117 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.018s	user 0.007s	sys 0.008s Metrics: {"bytes_written":4102660,"delete_count":0,"lbm_write_time_us":6591,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:19:27.983597 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:27.993317 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3692,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:27.994009 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushMRSOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:28.028734 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushMRSOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.034s	user 0.031s	sys 0.002s Metrics: {"bytes_written":1316414,"cfile_init":1,"dirs.queue_time_us":52,"dirs.run_cpu_time_us":228,"dirs.run_wall_time_us":1222,"drs_written":1,"lbm_read_time_us":40,"lbm_reads_lt_1ms":4,"lbm_write_time_us":2264,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":32,"spinlock_wait_cycles":7424}
I20260812 06:19:28.029676 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling LogGCOp(6b0934e0033349998e78dd4a7bda5851): free 129773808 bytes of WAL
I20260812 06:19:28.029922 31338 log_reader.cc:385] T 6b0934e0033349998e78dd4a7bda5851: removed 13 log segments from log reader
I20260812 06:19:28.029968 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000025 (ops 121-125)
I20260812 06:19:28.030066 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000026 (ops 126-130)
I20260812 06:19:28.030124 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000027 (ops 131-135)
I20260812 06:19:28.030167 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000028 (ops 136-140)
I20260812 06:19:28.030210 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000029 (ops 141-144)
I20260812 06:19:28.030249 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000030 (ops 145-149)
I20260812 06:19:28.030289 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000031 (ops 150-154)
I20260812 06:19:28.030331 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000032 (ops 155-159)
I20260812 06:19:28.030371 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000033 (ops 160-164)
I20260812 06:19:28.030411 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000034 (ops 165-169)
I20260812 06:19:28.030460 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000035 (ops 170-174)
I20260812 06:19:28.030498 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000036 (ops 175-179)
I20260812 06:19:28.030539 31338 log.cc:1079] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: Deleting log segment in path: /tmp/dist-test-taskroo9HY/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515556832741-30837-0/minicluster-data/ts-0-root/wals/6b0934e0033349998e78dd4a7bda5851/wal-000000037 (ops 180-184)
I20260812 06:19:28.058727 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: LogGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.029s	user 0.000s	sys 0.028s Metrics: {}
I20260812 06:19:28.059219 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851): 491 bytes on disk
I20260812 06:19:28.059800 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: UndoDeltaBlockGCOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":71,"lbm_reads_lt_1ms":4}
I20260812 06:19:28.060401 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=3.181125
I20260812 06:19:28.090394 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.030s	user 0.005s	sys 0.023s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":7383,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:19:28.091005 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:28.101395 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3875,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:28.101920 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:28.351121 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.249s	user 0.133s	sys 0.112s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":32979849,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":7144,"lbm_read_time_us":17868,"lbm_reads_lt_1ms":775,"lbm_write_time_us":43415,"lbm_writes_lt_1ms":743,"mutex_wait_us":2443,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":16640,"thread_start_us":79,"threads_started":1,"update_count":3500}
I20260812 06:19:28.351905 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=15.087375
I20260812 06:19:28.408877 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.057s	user 0.045s	sys 0.008s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":25530,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:19:28.409433 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851): perf score=2.188937
I20260812 06:19:28.422947 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: FlushDeltaMemStoresOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.013s	user 0.007s	sys 0.005s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4746,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:19:28.423488 31442 maintenance_manager.cc:419] P e7888b771de544c5b5afbc733d3fe431: Scheduling MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851): perf score=1.000000
I20260812 06:19:28.439244 30837 heavy-update-compaction-itest.cc:229] Time spent updating: real 5.172s	user 1.865s	sys 0.217s
I20260812 06:19:28.510517 30837 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.071s	user 0.002s	sys 0.000s
I20260812 06:19:28.511036 30837 tablet_server.cc:179] TabletServer@127.30.29.65:0 shutting down...
I20260812 06:19:28.570607 31338 maintenance_manager.cc:643] P e7888b771de544c5b5afbc733d3fe431: MajorDeltaCompactionOp(6b0934e0033349998e78dd4a7bda5851) complete. Timing: real 0.147s	user 0.095s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774675,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":367,"lbm_read_time_us":11476,"lbm_reads_lt_1ms":568,"lbm_write_time_us":26463,"lbm_writes_lt_1ms":543,"mutex_wait_us":20,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":27392,"update_count":2500}
I20260812 06:19:28.571624 30837 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:19:28.571898 30837 tablet_replica.cc:333] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431: stopping tablet replica
I20260812 06:19:28.572026 30837 raft_consensus.cc:2243] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:28.572248 30837 raft_consensus.cc:2272] T 6b0934e0033349998e78dd4a7bda5851 P e7888b771de544c5b5afbc733d3fe431 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:28.578312 30837 tablet_server.cc:196] TabletServer@127.30.29.65:0 shutdown complete.
I20260812 06:19:28.616307 30837 master.cc:562] Master@127.30.29.126:44247 shutting down...
I20260812 06:19:28.620579 30837 raft_consensus.cc:2243] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:19:28.620800 30837 raft_consensus.cc:2272] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:19:28.620901 30837 tablet_replica.cc:333] T 00000000000000000000000000000000 P d45c384fb41d4d4cbe0b423bf223c105: stopping tablet replica
I20260812 06:19:28.635033 30837 master.cc:584] Master@127.30.29.126:44247 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5657 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (11882 ms total)

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