[==========] 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:20:22.828675  2242 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.2.48.190:37717
I20260812 06:20:22.829646  2242 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:20:22.830227  2242 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:22.836498  2256 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:20:22.836588  2242 server_base.cc:1061] running on GCE node
W20260812 06:20:22.836642  2250 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:20:22.836848  2251 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:20:22.837316  2242 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:22.837409  2242 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:20:22.837450  2242 hybrid_clock.cc:648] HybridClock initialized: now 1786515622837449 us; error 0 us; skew 500 ppm
I20260812 06:20:22.839113  2242 webserver.cc:533] Webserver started at http://127.2.48.190:41697/ using document root <none> and password file <none>
I20260812 06:20:22.839640  2242 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:22.839699  2242 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:22.839949  2242 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:22.841578  2242 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/master-0-root/instance:
uuid: "9a41b0a8824a462790f6768b05e85e20"
format_stamp: "Formatted at 2026-08-12 06:20:22 on dist-test-slave-1l3l"
I20260812 06:20:22.845062  2242 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.004s	sys 0.000s
I20260812 06:20:22.847142  2264 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:20:22.848191  2242 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:20:22.848310  2242 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/master-0-root
uuid: "9a41b0a8824a462790f6768b05e85e20"
format_stamp: "Formatted at 2026-08-12 06:20:22 on dist-test-slave-1l3l"
I20260812 06:20:22.848399  2242 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-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:20:22.866703  2242 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:22.867285  2242 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:20:22.867442  2242 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:22.874781  2356 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.2.48.190:37717 every 8 connection(s)
I20260812 06:20:22.874789  2242 rpc_server.cc:307] RPC server started. Bound to: 127.2.48.190:37717
I20260812 06:20:22.877012  2357 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:20:22.882227  2357 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20: Bootstrap starting.
I20260812 06:20:22.884828  2357 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:22.885931  2357 log.cc:826] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20: Log is configured to *not* fsync() on all Append() calls
I20260812 06:20:22.887573  2357 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20: No bootstrap required, opened a new log
I20260812 06:20:22.890249  2357 raft_consensus.cc:359] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a41b0a8824a462790f6768b05e85e20" member_type: VOTER }
I20260812 06:20:22.890413  2357 raft_consensus.cc:385] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:22.890460  2357 raft_consensus.cc:740] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 9a41b0a8824a462790f6768b05e85e20, State: Initialized, Role: FOLLOWER
I20260812 06:20:22.890954  2357 consensus_queue.cc:260] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [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: "9a41b0a8824a462790f6768b05e85e20" member_type: VOTER }
I20260812 06:20:22.891079  2357 raft_consensus.cc:399] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:22.891122  2357 raft_consensus.cc:493] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:22.891203  2357 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:22.891929  2357 raft_consensus.cc:515] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a41b0a8824a462790f6768b05e85e20" member_type: VOTER }
I20260812 06:20:22.892297  2357 leader_election.cc:304] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [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: 9a41b0a8824a462790f6768b05e85e20; no voters: 
I20260812 06:20:22.892551  2357 leader_election.cc:290] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:22.892660  2361 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:22.892881  2361 raft_consensus.cc:697] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 1 LEADER]: Becoming Leader. State: Replica: 9a41b0a8824a462790f6768b05e85e20, State: Running, Role: LEADER
I20260812 06:20:22.893242  2361 consensus_queue.cc:237] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [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: "9a41b0a8824a462790f6768b05e85e20" member_type: VOTER }
I20260812 06:20:22.893496  2357 sys_catalog.cc:565] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:22.894971  2362 sys_catalog.cc:455] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "9a41b0a8824a462790f6768b05e85e20" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a41b0a8824a462790f6768b05e85e20" member_type: VOTER } }
I20260812 06:20:22.895100  2362 sys_catalog.cc:458] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:22.894968  2368 sys_catalog.cc:455] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 9a41b0a8824a462790f6768b05e85e20. Latest consensus state: current_term: 1 leader_uuid: "9a41b0a8824a462790f6768b05e85e20" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "9a41b0a8824a462790f6768b05e85e20" member_type: VOTER } }
I20260812 06:20:22.895404  2368 sys_catalog.cc:458] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:22.895454  2386 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:22.895649  2242 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:22.897564  2386 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:22.901978  2386 catalog_manager.cc:1383] Generated new cluster ID: 0ed48e5e321c406a89f7afb6706d027a
I20260812 06:20:22.902041  2386 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:22.924193  2386 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:22.925326  2386 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:22.937661  2386 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20: Generated new TSK 0
I20260812 06:20:22.938261  2386 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:22.960827  2242 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:22.963518  2396 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:20:22.963593  2400 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:20:22.963603  2393 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:20:22.963923  2242 server_base.cc:1061] running on GCE node
I20260812 06:20:22.964102  2242 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:22.964146  2242 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:20:22.964167  2242 hybrid_clock.cc:648] HybridClock initialized: now 1786515622964167 us; error 0 us; skew 500 ppm
I20260812 06:20:22.965147  2242 webserver.cc:533] Webserver started at http://127.2.48.129:39525/ using document root <none> and password file <none>
I20260812 06:20:22.965310  2242 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:22.965372  2242 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:22.965451  2242 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:22.965883  2242 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/instance:
uuid: "69ea57a492be43419153170d951400ef"
format_stamp: "Formatted at 2026-08-12 06:20:22 on dist-test-slave-1l3l"
I20260812 06:20:22.967679  2242 fs_manager.cc:696] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:20:22.968761  2407 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:20:22.969036  2242 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
I20260812 06:20:22.969115  2242 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root
uuid: "69ea57a492be43419153170d951400ef"
format_stamp: "Formatted at 2026-08-12 06:20:22 on dist-test-slave-1l3l"
I20260812 06:20:22.969184  2242 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-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:20:22.996991  2242 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:22.997500  2242 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:22.998066  2242 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:22.999128  2242 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:22.999194  2242 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:22.999250  2242 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:22.999281  2242 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:23.005894  2242 rpc_server.cc:307] RPC server started. Bound to: 127.2.48.129:36771
I20260812 06:20:23.005988  2508 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.2.48.129:36771 every 8 connection(s)
I20260812 06:20:23.017921  2510 heartbeater.cc:344] Connected to a master server at 127.2.48.190:37717
I20260812 06:20:23.018137  2510 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:23.018530  2510 heartbeater.cc:507] Master 127.2.48.190:37717 requested a full tablet report, sending...
I20260812 06:20:23.019855  2303 ts_manager.cc:194] Registered new tserver with Master: 69ea57a492be43419153170d951400ef (127.2.48.129:36771)
I20260812 06:20:23.019972  2242 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.013406717s
I20260812 06:20:23.021072  2303 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:42362
I20260812 06:20:23.033691  2303 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:42376:
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:20:23.046761  2452 tablet_service.cc:1511] Processing CreateTablet for tablet 902c46a621394ad5b12ffab8cdfe1434 (DEFAULT_TABLE table=heavy-update-compaction-test [id=9522efd4bb264c86bdcd0a7118dc8871]), partition=
I20260812 06:20:23.047204  2452 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 902c46a621394ad5b12ffab8cdfe1434. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:23.049468  2531 tablet_bootstrap.cc:492] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Bootstrap starting.
I20260812 06:20:23.050905  2531 tablet_bootstrap.cc:654] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:23.052275  2531 tablet_bootstrap.cc:492] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: No bootstrap required, opened a new log
I20260812 06:20:23.052392  2531 ts_tablet_manager.cc:1403] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Time spent bootstrapping tablet: real 0.003s	user 0.002s	sys 0.000s
I20260812 06:20:23.052935  2531 raft_consensus.cc:359] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "69ea57a492be43419153170d951400ef" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 36771 } }
I20260812 06:20:23.053076  2531 raft_consensus.cc:385] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:23.053122  2531 raft_consensus.cc:740] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 69ea57a492be43419153170d951400ef, State: Initialized, Role: FOLLOWER
I20260812 06:20:23.053256  2531 consensus_queue.cc:260] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [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: "69ea57a492be43419153170d951400ef" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 36771 } }
I20260812 06:20:23.053349  2531 raft_consensus.cc:399] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:23.053390  2531 raft_consensus.cc:493] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:23.053436  2531 raft_consensus.cc:3060] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:23.054363  2531 raft_consensus.cc:515] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "69ea57a492be43419153170d951400ef" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 36771 } }
I20260812 06:20:23.054517  2531 leader_election.cc:304] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [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: 69ea57a492be43419153170d951400ef; no voters: 
I20260812 06:20:23.054785  2531 leader_election.cc:290] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:23.054876  2533 raft_consensus.cc:2804] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:23.055080  2533 raft_consensus.cc:697] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 1 LEADER]: Becoming Leader. State: Replica: 69ea57a492be43419153170d951400ef, State: Running, Role: LEADER
I20260812 06:20:23.055142  2531 ts_tablet_manager.cc:1434] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Time spent starting tablet: real 0.003s	user 0.003s	sys 0.000s
I20260812 06:20:23.055308  2533 consensus_queue.cc:237] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [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: "69ea57a492be43419153170d951400ef" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 36771 } }
I20260812 06:20:23.055490  2510 heartbeater.cc:499] Master 127.2.48.190:37717 was elected leader, sending a full tablet report...
I20260812 06:20:23.057922  2303 catalog_manager.cc:5719] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef reported cstate change: term changed from 0 to 1, leader changed from <none> to 69ea57a492be43419153170d951400ef (127.2.48.129). New cstate: current_term: 1 leader_uuid: "69ea57a492be43419153170d951400ef" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "69ea57a492be43419153170d951400ef" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 36771 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:23.117139  2242 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.050s	user 0.011s	sys 0.012s
I20260812 06:20:23.257126  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434): perf score=19.054940
I20260812 06:20:23.445695  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.188s	user 0.151s	sys 0.032s Metrics: {"bytes_written":16409901,"cfile_init":1,"compiler_manager_pool.queue_time_us":188,"delete_count":0,"dirs.queue_time_us":56,"dirs.run_cpu_time_us":223,"dirs.run_wall_time_us":876,"drs_written":1,"lbm_read_time_us":71,"lbm_reads_lt_1ms":4,"lbm_write_time_us":46702,"lbm_writes_lt_1ms":857,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"thread_start_us":93,"threads_started":1,"update_count":2000}
I20260812 06:20:23.447096  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling LogGCOp(902c46a621394ad5b12ffab8cdfe1434): free 20743880 bytes of WAL
I20260812 06:20:23.447480  2415 log_reader.cc:385] T 902c46a621394ad5b12ffab8cdfe1434: removed 2 log segments from log reader
I20260812 06:20:23.447613  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000001 (ops 1-6)
I20260812 06:20:23.447763  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000002 (ops 7-11)
I20260812 06:20:23.452893  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: LogGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.006s	user 0.002s	sys 0.003s Metrics: {}
I20260812 06:20:23.453248  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=3.181125
I20260812 06:20:23.476181  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.023s	user 0.015s	sys 0.000s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":6057,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:23.476617  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:23.489355  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4840,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:23.489917  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434): 16411394 bytes on disk
I20260812 06:20:23.490576  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":69,"lbm_reads_lt_1ms":4}
I20260812 06:20:23.491020  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:23.681818  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.191s	user 0.119s	sys 0.067s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877206,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":483,"lbm_read_time_us":13397,"lbm_reads_lt_1ms":669,"lbm_write_time_us":31798,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1536,"thread_start_us":302,"threads_started":5,"update_count":3000}
I20260812 06:20:23.682335  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:23.738973  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.056s	user 0.025s	sys 0.020s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":20588,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:23.739519  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:23.750116  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.010s	user 0.005s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3698,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:23.750586  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:23.922274  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.172s	user 0.113s	sys 0.048s 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":306,"lbm_read_time_us":11043,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28588,"lbm_writes_lt_1ms":543,"mutex_wait_us":39,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":2816,"update_count":2500}
I20260812 06:20:23.922765  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:23.979954  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.057s	user 0.026s	sys 0.031s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21200,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:20:23.980526  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:23.990875  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.010s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3670,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:23.991521  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:24.168984  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.177s	user 0.127s	sys 0.039s 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":243,"lbm_read_time_us":11357,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29054,"lbm_writes_lt_1ms":543,"mutex_wait_us":1,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:24.169476  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:24.220001  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.050s	user 0.036s	sys 0.012s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":17504,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:24.220569  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:24.236187  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.015s	user 0.015s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5964,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.236606  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:24.422464  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.186s	user 0.099s	sys 0.067s 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":1027,"lbm_read_time_us":12033,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28008,"lbm_writes_lt_1ms":543,"mutex_wait_us":65,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":1792,"update_count":2500}
I20260812 06:20:24.423097  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:24.462708  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.039s	user 0.017s	sys 0.020s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":17412,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:24.463217  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:24.475162  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.012s	user 0.007s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4336,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.475612  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:24.646389  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.171s	user 0.110s	sys 0.055s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774689,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":153,"lbm_read_time_us":10697,"lbm_reads_lt_1ms":564,"lbm_write_time_us":26604,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"update_count":2500}
I20260812 06:20:24.646862  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:24.695115  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.048s	user 0.036s	sys 0.004s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":18885,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:24.695617  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:24.705265  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.009s	user 0.003s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":3550,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.705809  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:24.736486  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.030s	user 0.027s	sys 0.003s Metrics: {"bytes_written":1275443,"cfile_init":1,"dirs.queue_time_us":52,"dirs.run_cpu_time_us":230,"dirs.run_wall_time_us":1333,"drs_written":1,"lbm_read_time_us":34,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1730,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:20:24.737250  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling LogGCOp(902c46a621394ad5b12ffab8cdfe1434): free 121006426 bytes of WAL
I20260812 06:20:24.737429  2415 log_reader.cc:385] T 902c46a621394ad5b12ffab8cdfe1434: removed 12 log segments from log reader
I20260812 06:20:24.737469  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000003 (ops 12-16)
I20260812 06:20:24.737499  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000004 (ops 17-21)
I20260812 06:20:24.737531  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000005 (ops 22-26)
I20260812 06:20:24.737565  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000006 (ops 27-31)
I20260812 06:20:24.737597  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000007 (ops 32-36)
I20260812 06:20:24.737627  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000008 (ops 37-40)
I20260812 06:20:24.737658  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000009 (ops 41-45)
I20260812 06:20:24.737690  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000010 (ops 46-50)
I20260812 06:20:24.737720  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000011 (ops 51-55)
I20260812 06:20:24.737751  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000012 (ops 56-60)
I20260812 06:20:24.737782  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000013 (ops 61-65)
I20260812 06:20:24.737812  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000014 (ops 66-70)
I20260812 06:20:24.758512  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: LogGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.021s	user 0.000s	sys 0.018s Metrics: {}
I20260812 06:20:24.763906  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:24.784870  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.021s	user 0.009s	sys 0.005s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5847,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.785347  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling LogGCOp(902c46a621394ad5b12ffab8cdfe1434): free 11564875 bytes of WAL
I20260812 06:20:24.785584  2415 log_reader.cc:385] T 902c46a621394ad5b12ffab8cdfe1434: removed 1 log segments from log reader
I20260812 06:20:24.785631  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000015 (ops 71-74)
I20260812 06:20:24.787552  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: LogGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.002s	user 0.000s	sys 0.000s Metrics: {}
I20260812 06:20:24.787869  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:24.798234  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3651,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:24.798782  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:25.020048  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.221s	user 0.132s	sys 0.084s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979750,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":275,"lbm_read_time_us":15577,"lbm_reads_lt_1ms":774,"lbm_write_time_us":35708,"lbm_writes_lt_1ms":743,"mutex_wait_us":48,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":5632,"thread_start_us":119,"threads_started":1,"update_count":3500}
I20260812 06:20:25.020624  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434): 483 bytes on disk
I20260812 06:20:25.021144  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":62,"lbm_reads_lt_1ms":4}
I20260812 06:20:25.021659  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=18.063937
I20260812 06:20:25.087055  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.065s	user 0.031s	sys 0.020s Metrics: {"bytes_written":20143100,"delete_count":0,"lbm_write_time_us":24837,"lbm_writes_lt_1ms":494,"reinsert_count":0,"update_count":2455}
I20260812 06:20:25.087604  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=3.181125
I20260812 06:20:25.099825  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.012s	user 0.011s	sys 0.000s Metrics: {"bytes_written":4471879,"delete_count":0,"lbm_write_time_us":4156,"lbm_writes_lt_1ms":112,"reinsert_count":0,"update_count":545}
I20260812 06:20:25.100445  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:25.294763  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.193s	user 0.125s	sys 0.068s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877107,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":573,"lbm_read_time_us":14162,"lbm_reads_lt_1ms":672,"lbm_write_time_us":33398,"lbm_writes_lt_1ms":643,"mutex_wait_us":26,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":3968,"update_count":3000}
I20260812 06:20:25.295444  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:25.351256  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.056s	user 0.028s	sys 0.020s Metrics: {"bytes_written":16409902,"delete_count":0,"lbm_write_time_us":23446,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:20:25.351802  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=3.181125
I20260812 06:20:25.362605  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.011s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":4121,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:25.363041  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:25.372260  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.009s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3449,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:25.372717  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:25.564430  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.191s	user 0.107s	sys 0.084s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877207,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":986,"lbm_read_time_us":14013,"lbm_reads_lt_1ms":673,"lbm_write_time_us":31749,"lbm_writes_lt_1ms":643,"mutex_wait_us":355,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":7424,"update_count":3000}
I20260812 06:20:25.568162  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:25.612123  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.044s	user 0.023s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":17438,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:25.612774  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:25.628245  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.015s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5592,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.628656  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:25.789081  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.160s	user 0.111s	sys 0.047s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":122,"lbm_read_time_us":10675,"lbm_reads_lt_1ms":564,"lbm_write_time_us":27165,"lbm_writes_lt_1ms":543,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2500}
I20260812 06:20:25.790198  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:25.832829  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.042s	user 0.030s	sys 0.011s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":18423,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:25.833505  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:25.845969  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.012s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4606,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:25.846553  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:26.017529  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.171s	user 0.104s	sys 0.066s 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":591,"lbm_read_time_us":13043,"lbm_reads_lt_1ms":572,"lbm_write_time_us":27142,"lbm_writes_lt_1ms":543,"mutex_wait_us":315,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":4352,"update_count":2500}
I20260812 06:20:26.018090  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:26.080158  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.062s	user 0.038s	sys 0.020s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":21254,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:26.080869  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:26.097082  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.016s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5965,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.097677  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:26.138288  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.040s	user 0.027s	sys 0.003s Metrics: {"bytes_written":1193509,"cfile_init":1,"dirs.queue_time_us":63,"dirs.run_cpu_time_us":262,"dirs.run_wall_time_us":1449,"drs_written":1,"lbm_read_time_us":49,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1604,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":29}
I20260812 06:20:26.139127  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling LogGCOp(902c46a621394ad5b12ffab8cdfe1434): free 112692378 bytes of WAL
I20260812 06:20:26.139390  2415 log_reader.cc:385] T 902c46a621394ad5b12ffab8cdfe1434: removed 11 log segments from log reader
I20260812 06:20:26.139472  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000016 (ops 75-79)
I20260812 06:20:26.139515  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000017 (ops 80-84)
I20260812 06:20:26.139549  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000018 (ops 85-89)
I20260812 06:20:26.139577  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000019 (ops 90-94)
I20260812 06:20:26.139605  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000020 (ops 95-99)
I20260812 06:20:26.139637  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000021 (ops 100-104)
I20260812 06:20:26.139668  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000022 (ops 105-109)
I20260812 06:20:26.139695  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000023 (ops 110-114)
I20260812 06:20:26.139748  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000024 (ops 115-119)
I20260812 06:20:26.139778  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000025 (ops 120-124)
I20260812 06:20:26.139808  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000026 (ops 125-129)
I20260812 06:20:26.166354  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: LogGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.027s	user 0.000s	sys 0.024s Metrics: {}
I20260812 06:20:26.166848  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=3.181125
I20260812 06:20:26.189011  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.022s	user 0.008s	sys 0.008s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":6764,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:26.189630  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:26.199896  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.010s	user 0.010s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3636,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:26.200527  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434): 462 bytes on disk
I20260812 06:20:26.201073  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":63,"lbm_reads_lt_1ms":4}
I20260812 06:20:26.201658  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:26.433636  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.232s	user 0.123s	sys 0.099s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979737,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":133,"lbm_read_time_us":15407,"lbm_reads_lt_1ms":774,"lbm_write_time_us":42688,"lbm_writes_lt_1ms":743,"peak_mem_usage":87969044,"reinsert_count":0,"thread_start_us":83,"threads_started":1,"update_count":3500}
I20260812 06:20:26.434144  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=15.087375
I20260812 06:20:26.489010  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.054s	user 0.035s	sys 0.011s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":21002,"lbm_writes_lt_1ms":413,"reinsert_count":0,"update_count":2050}
I20260812 06:20:26.489694  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:26.509167  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.019s	user 0.005s	sys 0.004s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":4254,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:26.509651  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:26.520233  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.010s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3751,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.520736  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:26.717612  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.197s	user 0.149s	sys 0.047s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877206,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":1048,"lbm_read_time_us":15455,"lbm_reads_lt_1ms":673,"lbm_write_time_us":32725,"lbm_writes_lt_1ms":643,"mutex_wait_us":761,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":26624,"update_count":3000}
I20260812 06:20:26.718237  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:26.775105  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.057s	user 0.033s	sys 0.020s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":25494,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:26.775780  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:26.800916  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.025s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5462,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.801463  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:26.812398  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.011s	user 0.006s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3920,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:26.813035  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:26.990722  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.177s	user 0.145s	sys 0.032s Metrics: {"cfile_cache_miss":633,"cfile_cache_miss_bytes":28877222,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":3,"delta_iterators_relevant":3,"dirs.queue_time_us":219,"lbm_read_time_us":13659,"lbm_reads_lt_1ms":673,"lbm_write_time_us":36159,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":3000}
I20260812 06:20:26.991365  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=14.095187
I20260812 06:20:27.040436  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.049s	user 0.035s	sys 0.010s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":20551,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:27.041004  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:27.053507  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.012s	user 0.006s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4617,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.054067  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:27.196455  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.142s	user 0.105s	sys 0.034s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774691,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":206,"lbm_read_time_us":10560,"lbm_reads_lt_1ms":568,"lbm_write_time_us":26046,"lbm_writes_lt_1ms":543,"mutex_wait_us":60,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":20736,"update_count":2500}
I20260812 06:20:27.197229  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=12.110812
I20260812 06:20:27.231660  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.034s	user 0.014s	sys 0.016s Metrics: {"bytes_written":13579242,"delete_count":0,"lbm_write_time_us":14393,"lbm_writes_lt_1ms":334,"reinsert_count":0,"update_count":1655}
I20260812 06:20:27.234913  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.196750
I20260812 06:20:27.245925  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":2830880,"delete_count":0,"lbm_write_time_us":3391,"lbm_writes_lt_1ms":72,"reinsert_count":0,"update_count":345}
I20260812 06:20:27.246416  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:27.386286  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.140s	user 0.081s	sys 0.058s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672250,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":667,"lbm_read_time_us":11094,"lbm_reads_lt_1ms":464,"lbm_write_time_us":23168,"lbm_writes_lt_1ms":443,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":3328,"update_count":2000}
I20260812 06:20:27.387773  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=10.126437
I20260812 06:20:27.425081  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.037s	user 0.030s	sys 0.006s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15880,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:27.425642  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:27.446803  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.021s	user 0.012s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5512,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.447350  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:27.472240  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.025s	user 0.013s	sys 0.011s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5740,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.472993  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:27.516870  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushMRSOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.044s	user 0.029s	sys 0.000s Metrics: {"bytes_written":1152510,"cfile_init":1,"dirs.queue_time_us":91,"dirs.run_cpu_time_us":192,"dirs.run_wall_time_us":1354,"drs_written":1,"lbm_read_time_us":56,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1549,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:27.517715  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling LogGCOp(902c46a621394ad5b12ffab8cdfe1434): free 120553594 bytes of WAL
I20260812 06:20:27.517982  2415 log_reader.cc:385] T 902c46a621394ad5b12ffab8cdfe1434: removed 12 log segments from log reader
I20260812 06:20:27.518046  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000027 (ops 130-134)
I20260812 06:20:27.518078  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000028 (ops 135-138)
I20260812 06:20:27.518098  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000029 (ops 139-143)
I20260812 06:20:27.518129  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000030 (ops 144-148)
I20260812 06:20:27.518162  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000031 (ops 149-153)
I20260812 06:20:27.518193  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000032 (ops 154-158)
I20260812 06:20:27.518222  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000033 (ops 159-163)
I20260812 06:20:27.518254  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000034 (ops 164-168)
I20260812 06:20:27.518285  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000035 (ops 169-173)
I20260812 06:20:27.518316  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000036 (ops 174-178)
I20260812 06:20:27.518347  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000037 (ops 179-182)
I20260812 06:20:27.518378  2415 log.cc:1079] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_0.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/902c46a621394ad5b12ffab8cdfe1434/wal-000000038 (ops 183-187)
I20260812 06:20:27.541157  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: LogGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.023s	user 0.000s	sys 0.022s Metrics: {}
I20260812 06:20:27.541630  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434): 446 bytes on disk
I20260812 06:20:27.542096  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: UndoDeltaBlockGCOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":48,"lbm_reads_lt_1ms":4}
I20260812 06:20:27.542737  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=3.181125
I20260812 06:20:27.561148  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.018s	user 0.003s	sys 0.007s Metrics: {"bytes_written":4512900,"delete_count":0,"lbm_write_time_us":4478,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:27.561615  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:27.571497  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.010s	user 0.004s	sys 0.003s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3464,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:27.571982  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:27.806510  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.234s	user 0.167s	sys 0.062s Metrics: {"cfile_cache_miss":735,"cfile_cache_miss_bytes":32979857,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":5,"delta_iterators_relevant":5,"dirs.queue_time_us":796,"lbm_read_time_us":17255,"lbm_reads_lt_1ms":775,"lbm_write_time_us":36868,"lbm_writes_lt_1ms":743,"mutex_wait_us":568,"peak_mem_usage":87969044,"reinsert_count":0,"thread_start_us":92,"threads_started":1,"update_count":3500}
I20260812 06:20:27.807806  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=18.063937
I20260812 06:20:27.878332  2242 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.761s	user 1.699s	sys 0.166s
I20260812 06:20:27.882230  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.074s	user 0.030s	sys 0.036s Metrics: {"bytes_written":20512317,"delete_count":0,"lbm_write_time_us":31395,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":501,"reinsert_count":0,"update_count":2500}
I20260812 06:20:27.882745  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434): perf score=2.188937
I20260812 06:20:27.892586  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: FlushDeltaMemStoresOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.010s	user 0.009s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3722,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:27.893173  2512 maintenance_manager.cc:419] P 69ea57a492be43419153170d951400ef: Scheduling MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434): perf score=1.000000
I20260812 06:20:27.952555  2242 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.074s	user 0.005s	sys 0.000s
I20260812 06:20:27.953394  2242 tablet_server.cc:179] TabletServer@127.2.48.129:0 shutting down...
I20260812 06:20:28.044525  2415 maintenance_manager.cc:643] P 69ea57a492be43419153170d951400ef: MajorDeltaCompactionOp(902c46a621394ad5b12ffab8cdfe1434) complete. Timing: real 0.151s	user 0.116s	sys 0.035s Metrics: {"cfile_cache_hit":243,"cfile_cache_hit_bytes":9931345,"cfile_cache_miss":389,"cfile_cache_miss_bytes":18945759,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":280,"lbm_read_time_us":8045,"lbm_reads_lt_1ms":421,"lbm_write_time_us":28151,"lbm_writes_lt_1ms":643,"mutex_wait_us":64,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":233984,"update_count":3000}
I20260812 06:20:28.045264  2242 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:28.045747  2242 tablet_replica.cc:333] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef: stopping tablet replica
I20260812 06:20:28.045994  2242 raft_consensus.cc:2243] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:28.046257  2242 raft_consensus.cc:2272] T 902c46a621394ad5b12ffab8cdfe1434 P 69ea57a492be43419153170d951400ef [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:28.062620  2242 tablet_server.cc:196] TabletServer@127.2.48.129:0 shutdown complete.
I20260812 06:20:28.097405  2242 master.cc:562] Master@127.2.48.190:37717 shutting down...
I20260812 06:20:28.100903  2242 raft_consensus.cc:2243] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:28.101109  2242 raft_consensus.cc:2272] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20 [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:28.101189  2242 tablet_replica.cc:333] T 00000000000000000000000000000000 P 9a41b0a8824a462790f6768b05e85e20: stopping tablet replica
I20260812 06:20:28.113627  2242 master.cc:584] Master@127.2.48.190:37717 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/0 (5364 ms)
[ RUN      ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1
I20260812 06:20:28.204770  2242 internal_mini_cluster.cc:156] Creating distributed mini masters. Addrs: 127.2.48.190:45473
I20260812 06:20:28.205209  2242 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:28.207412  2560 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:20:28.207469  2558 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:20:28.207634  2242 server_base.cc:1061] running on GCE node
W20260812 06:20:28.207612  2563 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:20:28.207892  2242 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:28.207950  2242 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:20:28.207965  2242 hybrid_clock.cc:648] HybridClock initialized: now 1786515628207966 us; error 0 us; skew 500 ppm
I20260812 06:20:28.209007  2242 webserver.cc:533] Webserver started at http://127.2.48.190:35399/ using document root <none> and password file <none>
I20260812 06:20:28.209151  2242 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:28.209192  2242 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:28.209250  2242 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:28.209626  2242 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/master-0-root/instance:
uuid: "d6ce4617999548948f178f8ca988cabc"
format_stamp: "Formatted at 2026-08-12 06:20:28 on dist-test-slave-1l3l"
I20260812 06:20:28.211133  2242 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
I20260812 06:20:28.212409  2570 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:20:28.212657  2242 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.000s
I20260812 06:20:28.212735  2242 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/master-0-root
uuid: "d6ce4617999548948f178f8ca988cabc"
format_stamp: "Formatted at 2026-08-12 06:20:28 on dist-test-slave-1l3l"
I20260812 06:20:28.212816  2242 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/master-0-root
metadata directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/master-0-root
1 data directories: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-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:20:28.232309  2242 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:28.232764  2242 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:28.237180  2242 rpc_server.cc:307] RPC server started. Bound to: 127.2.48.190:45473
I20260812 06:20:28.241952  2655 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.2.48.190:45473 every 8 connection(s)
I20260812 06:20:28.242468  2656 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:20:28.244488  2656 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc: Bootstrap starting.
I20260812 06:20:28.245291  2656 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:28.246321  2656 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc: No bootstrap required, opened a new log
I20260812 06:20:28.246723  2656 raft_consensus.cc:359] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d6ce4617999548948f178f8ca988cabc" member_type: VOTER }
I20260812 06:20:28.246811  2656 raft_consensus.cc:385] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:28.246840  2656 raft_consensus.cc:740] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d6ce4617999548948f178f8ca988cabc, State: Initialized, Role: FOLLOWER
I20260812 06:20:28.246966  2656 consensus_queue.cc:260] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [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: "d6ce4617999548948f178f8ca988cabc" member_type: VOTER }
I20260812 06:20:28.247056  2656 raft_consensus.cc:399] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:28.247087  2656 raft_consensus.cc:493] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:28.247123  2656 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:28.247864  2656 raft_consensus.cc:515] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d6ce4617999548948f178f8ca988cabc" member_type: VOTER }
I20260812 06:20:28.248000  2656 leader_election.cc:304] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [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: d6ce4617999548948f178f8ca988cabc; no voters: 
I20260812 06:20:28.248180  2656 leader_election.cc:290] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:28.248333  2662 raft_consensus.cc:2804] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:28.248533  2662 raft_consensus.cc:697] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 1 LEADER]: Becoming Leader. State: Replica: d6ce4617999548948f178f8ca988cabc, State: Running, Role: LEADER
I20260812 06:20:28.248615  2656 sys_catalog.cc:565] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [sys.catalog]: configured and running, proceeding with master startup.
I20260812 06:20:28.248687  2662 consensus_queue.cc:237] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [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: "d6ce4617999548948f178f8ca988cabc" member_type: VOTER }
I20260812 06:20:28.249163  2663 sys_catalog.cc:455] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d6ce4617999548948f178f8ca988cabc" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d6ce4617999548948f178f8ca988cabc" member_type: VOTER } }
I20260812 06:20:28.249188  2664 sys_catalog.cc:455] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [sys.catalog]: SysCatalogTable state changed. Reason: New leader d6ce4617999548948f178f8ca988cabc. Latest consensus state: current_term: 1 leader_uuid: "d6ce4617999548948f178f8ca988cabc" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d6ce4617999548948f178f8ca988cabc" member_type: VOTER } }
I20260812 06:20:28.249333  2664 sys_catalog.cc:458] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:28.249588  2663 sys_catalog.cc:458] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [sys.catalog]: This master's current role is: LEADER
I20260812 06:20:28.249917  2669 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
I20260812 06:20:28.250602  2669 catalog_manager.cc:1520] Initializing Kudu cluster ID...
I20260812 06:20:28.250802  2242 internal_mini_cluster.cc:184] Waiting to initialize catalog manager on master 0
I20260812 06:20:28.252450  2669 catalog_manager.cc:1383] Generated new cluster ID: e5fd8d2a796b40f788e5e424d745b2cb
I20260812 06:20:28.252501  2669 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
I20260812 06:20:28.269881  2669 catalog_manager.cc:1406] Generated new certificate authority record
I20260812 06:20:28.270490  2669 catalog_manager.cc:1540] Loading token signing keys...
I20260812 06:20:28.279929  2669 catalog_manager.cc:6092] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc: Generated new TSK 0
I20260812 06:20:28.280185  2669 catalog_manager.cc:1550] Initializing in-progress tserver states...
I20260812 06:20:28.283234  2242 file_cache.cc:504] Constructed file cache file cache with capacity 419430
W20260812 06:20:28.285475  2690 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:20:28.285593  2691 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:20:28.285641  2242 server_base.cc:1061] running on GCE node
W20260812 06:20:28.285600  2693 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:20:28.285926  2242 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
W20260812 06:20:28.285981  2242 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:20:28.285996  2242 hybrid_clock.cc:648] HybridClock initialized: now 1786515628285997 us; error 0 us; skew 500 ppm
I20260812 06:20:28.286855  2242 webserver.cc:533] Webserver started at http://127.2.48.129:39021/ using document root <none> and password file <none>
I20260812 06:20:28.287038  2242 fs_manager.cc:362] Metadata directory not provided
I20260812 06:20:28.287093  2242 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
I20260812 06:20:28.287176  2242 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
I20260812 06:20:28.287581  2242 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/instance:
uuid: "5eefc7a0693e4e37a06ca37f5b6102ed"
format_stamp: "Formatted at 2026-08-12 06:20:28 on dist-test-slave-1l3l"
I20260812 06:20:28.289206  2242 fs_manager.cc:696] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:20:28.290156  2700 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:20:28.290400  2242 fs_manager.cc:730] Time spent opening block manager: real 0.001s	user 0.000s	sys 0.001s
I20260812 06:20:28.290472  2242 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root
uuid: "5eefc7a0693e4e37a06ca37f5b6102ed"
format_stamp: "Formatted at 2026-08-12 06:20:28 on dist-test-slave-1l3l"
I20260812 06:20:28.290545  2242 fs_report.cc:389] FS layout report
--------------------
wal directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root
metadata directory: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root
1 data directories: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-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:20:28.300320  2242 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
I20260812 06:20:28.300771  2242 kserver.cc:163] Server-wide thread pool size limit: 3276
I20260812 06:20:28.301103  2242 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
I20260812 06:20:28.301605  2242 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
I20260812 06:20:28.301644  2242 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:28.301690  2242 ts_tablet_manager.cc:616] Registered 0 tablets
I20260812 06:20:28.301720  2242 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
I20260812 06:20:28.306021  2242 rpc_server.cc:307] RPC server started. Bound to: 127.2.48.129:42269
I20260812 06:20:28.306062  2814 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.2.48.129:42269 every 8 connection(s)
I20260812 06:20:28.315917  2817 heartbeater.cc:344] Connected to a master server at 127.2.48.190:45473
I20260812 06:20:28.316048  2817 heartbeater.cc:461] Registering TS with master...
I20260812 06:20:28.316372  2817 heartbeater.cc:507] Master 127.2.48.190:45473 requested a full tablet report, sending...
I20260812 06:20:28.317039  2593 ts_manager.cc:194] Registered new tserver with Master: 5eefc7a0693e4e37a06ca37f5b6102ed (127.2.48.129:42269)
I20260812 06:20:28.317479  2242 internal_mini_cluster.cc:371] 1 TS(s) registered with all masters after 0.011003573s
I20260812 06:20:28.317850  2593 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.0.0.1:58452
I20260812 06:20:28.324803  2593 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:58456:
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:20:28.333779  2741 tablet_service.cc:1511] Processing CreateTablet for tablet 304ce1d949e54e61872489d339f74d7c (DEFAULT_TABLE table=heavy-update-compaction-test [id=54f0789509cd4533a89461128e36d37d]), partition=
I20260812 06:20:28.334105  2741 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 304ce1d949e54e61872489d339f74d7c. 1 dirs total, 0 dirs full, 0 dirs failed
I20260812 06:20:28.336243  2835 tablet_bootstrap.cc:492] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Bootstrap starting.
I20260812 06:20:28.337136  2835 tablet_bootstrap.cc:654] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Neither blocks nor log segments found. Creating new log.
I20260812 06:20:28.338258  2835 tablet_bootstrap.cc:492] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: No bootstrap required, opened a new log
I20260812 06:20:28.338343  2835 ts_tablet_manager.cc:1403] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Time spent bootstrapping tablet: real 0.002s	user 0.002s	sys 0.000s
I20260812 06:20:28.338795  2835 raft_consensus.cc:359] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5eefc7a0693e4e37a06ca37f5b6102ed" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 42269 } }
I20260812 06:20:28.338891  2835 raft_consensus.cc:385] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
I20260812 06:20:28.338927  2835 raft_consensus.cc:740] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5eefc7a0693e4e37a06ca37f5b6102ed, State: Initialized, Role: FOLLOWER
I20260812 06:20:28.339067  2835 consensus_queue.cc:260] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [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: "5eefc7a0693e4e37a06ca37f5b6102ed" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 42269 } }
I20260812 06:20:28.339145  2835 raft_consensus.cc:399] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
I20260812 06:20:28.339170  2835 raft_consensus.cc:493] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
I20260812 06:20:28.339217  2835 raft_consensus.cc:3060] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 0 FOLLOWER]: Advancing to term 1
I20260812 06:20:28.340093  2835 raft_consensus.cc:515] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5eefc7a0693e4e37a06ca37f5b6102ed" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 42269 } }
I20260812 06:20:28.340250  2835 leader_election.cc:304] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [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: 5eefc7a0693e4e37a06ca37f5b6102ed; no voters: 
I20260812 06:20:28.340461  2835 leader_election.cc:290] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [CANDIDATE]: Term 1 election: Requested vote from peers 
I20260812 06:20:28.340615  2844 raft_consensus.cc:2804] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 1 FOLLOWER]: Leader election won for term 1
I20260812 06:20:28.340822  2835 ts_tablet_manager.cc:1434] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Time spent starting tablet: real 0.002s	user 0.003s	sys 0.000s
I20260812 06:20:28.340902  2817 heartbeater.cc:499] Master 127.2.48.190:45473 was elected leader, sending a full tablet report...
I20260812 06:20:28.340847  2844 raft_consensus.cc:697] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 1 LEADER]: Becoming Leader. State: Replica: 5eefc7a0693e4e37a06ca37f5b6102ed, State: Running, Role: LEADER
I20260812 06:20:28.341066  2844 consensus_queue.cc:237] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [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: "5eefc7a0693e4e37a06ca37f5b6102ed" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 42269 } }
I20260812 06:20:28.342535  2593 catalog_manager.cc:5719] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed reported cstate change: term changed from 0 to 1, leader changed from <none> to 5eefc7a0693e4e37a06ca37f5b6102ed (127.2.48.129). New cstate: current_term: 1 leader_uuid: "5eefc7a0693e4e37a06ca37f5b6102ed" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "5eefc7a0693e4e37a06ca37f5b6102ed" member_type: VOTER last_known_addr { host: "127.2.48.129" port: 42269 } health_report { overall_health: HEALTHY } } }
I20260812 06:20:28.401983  2242 heavy-update-compaction-itest.cc:215] Time spent inserting: real 0.052s	user 0.018s	sys 0.004s
I20260812 06:20:28.557024  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushMRSOp(304ce1d949e54e61872489d339f74d7c): perf score=19.054940
I20260812 06:20:28.710534  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushMRSOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.153s	user 0.103s	sys 0.048s Metrics: {"bytes_written":12594659,"cfile_init":1,"delete_count":0,"dirs.queue_time_us":64,"dirs.run_cpu_time_us":183,"dirs.run_wall_time_us":759,"drs_written":1,"lbm_read_time_us":76,"lbm_reads_lt_1ms":4,"lbm_write_time_us":36244,"lbm_writes_lt_1ms":764,"peak_mem_usage":0,"reinsert_count":0,"rows_written":104,"spinlock_wait_cycles":11008,"update_count":1535}
I20260812 06:20:28.711442  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling LogGCOp(304ce1d949e54e61872489d339f74d7c): free 20743880 bytes of WAL
I20260812 06:20:28.711807  2708 log_reader.cc:385] T 304ce1d949e54e61872489d339f74d7c: removed 2 log segments from log reader
I20260812 06:20:28.711879  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000001 (ops 1-6)
I20260812 06:20:28.711927  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000002 (ops 7-11)
I20260812 06:20:28.717448  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: LogGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.006s	user 0.001s	sys 0.003s Metrics: {}
I20260812 06:20:28.717907  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:28.730973  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.013s	user 0.007s	sys 0.004s Metrics: {"bytes_written":3815484,"delete_count":0,"lbm_write_time_us":4708,"lbm_writes_lt_1ms":96,"reinsert_count":0,"update_count":465}
I20260812 06:20:28.731467  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c): 16411394 bytes on disk
I20260812 06:20:28.731973  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":61,"lbm_reads_lt_1ms":4}
I20260812 06:20:28.732443  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:28.889492  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.157s	user 0.109s	sys 0.047s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672271,"cfile_init":7,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":934,"lbm_read_time_us":9782,"lbm_reads_lt_1ms":460,"lbm_write_time_us":25621,"lbm_writes_lt_1ms":443,"mutex_wait_us":298,"peak_mem_usage":50689328,"reinsert_count":0,"thread_start_us":349,"threads_started":5,"update_count":2000}
I20260812 06:20:28.890039  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=10.126437
I20260812 06:20:28.926776  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.037s	user 0.017s	sys 0.016s Metrics: {"bytes_written":12307492,"delete_count":0,"lbm_write_time_us":15831,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:28.927336  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:28.939250  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.012s	user 0.002s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4595,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:28.939759  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:29.064288  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.124s	user 0.103s	sys 0.020s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672279,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":357,"lbm_read_time_us":9792,"lbm_reads_lt_1ms":472,"lbm_write_time_us":20554,"lbm_writes_lt_1ms":443,"mutex_wait_us":66,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":8832,"update_count":2000}
I20260812 06:20:29.064850  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=10.126437
I20260812 06:20:29.104368  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.039s	user 0.028s	sys 0.004s Metrics: {"bytes_written":12307492,"delete_count":0,"lbm_write_time_us":14327,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:29.104925  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:29.120230  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.015s	user 0.010s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5547,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.120793  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:29.242830  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.122s	user 0.085s	sys 0.036s Metrics: {"cfile_cache_miss":432,"cfile_cache_miss_bytes":20672279,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":220,"lbm_read_time_us":9767,"lbm_reads_lt_1ms":472,"lbm_write_time_us":22251,"lbm_writes_lt_1ms":443,"mutex_wait_us":46,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":3072,"update_count":2000}
I20260812 06:20:29.243395  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=10.126437
I20260812 06:20:29.285385  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.042s	user 0.023s	sys 0.007s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":13995,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:29.285945  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:29.296363  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.010s	user 0.010s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3741,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.297026  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:29.422969  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.126s	user 0.089s	sys 0.035s 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":372,"lbm_read_time_us":10423,"lbm_reads_lt_1ms":472,"lbm_write_time_us":24019,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":442,"mutex_wait_us":50,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":16256,"update_count":2000}
I20260812 06:20:29.423756  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=10.126437
I20260812 06:20:29.467933  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.044s	user 0.020s	sys 0.020s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":13788,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:29.468539  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:29.478826  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.010s	user 0.000s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3889,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.479344  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:29.618428  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.139s	user 0.087s	sys 0.051s 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":858,"lbm_read_time_us":10432,"lbm_reads_lt_1ms":472,"lbm_write_time_us":20633,"lbm_writes_lt_1ms":443,"mutex_wait_us":323,"peak_mem_usage":50689328,"reinsert_count":0,"update_count":2000}
I20260812 06:20:29.619020  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=10.126437
I20260812 06:20:29.665561  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.046s	user 0.023s	sys 0.011s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15561,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:29.666173  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:29.681558  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.015s	user 0.009s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5565,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.682118  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:29.811197  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.129s	user 0.105s	sys 0.024s 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":263,"lbm_read_time_us":9624,"lbm_reads_lt_1ms":472,"lbm_write_time_us":25963,"lbm_writes_lt_1ms":443,"mutex_wait_us":42,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":5632,"update_count":2000}
I20260812 06:20:29.811769  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=10.126437
I20260812 06:20:29.856618  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.045s	user 0.027s	sys 0.008s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":15984,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:29.857071  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:29.867266  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.010s	user 0.008s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3806,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:29.867846  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushMRSOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:29.894606  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushMRSOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.026s	user 0.024s	sys 0.000s Metrics: {"bytes_written":1152508,"cfile_init":1,"dirs.queue_time_us":76,"dirs.run_cpu_time_us":238,"dirs.run_wall_time_us":1574,"drs_written":1,"lbm_read_time_us":51,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1373,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:29.895345  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling LogGCOp(304ce1d949e54e61872489d339f74d7c): free 112692374 bytes of WAL
I20260812 06:20:29.895586  2708 log_reader.cc:385] T 304ce1d949e54e61872489d339f74d7c: removed 11 log segments from log reader
I20260812 06:20:29.895635  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000003 (ops 12-16)
I20260812 06:20:29.895664  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000004 (ops 17-21)
I20260812 06:20:29.895680  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000005 (ops 22-26)
I20260812 06:20:29.895730  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000006 (ops 27-31)
I20260812 06:20:29.895767  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000007 (ops 32-36)
I20260812 06:20:29.895787  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000008 (ops 37-41)
I20260812 06:20:29.895817  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000009 (ops 42-46)
I20260812 06:20:29.895848  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000010 (ops 47-51)
I20260812 06:20:29.895880  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000011 (ops 52-56)
I20260812 06:20:29.895925  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000012 (ops 57-61)
I20260812 06:20:29.895956  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000013 (ops 62-66)
I20260812 06:20:29.919106  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: LogGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.024s	user 0.000s	sys 0.020s Metrics: {}
I20260812 06:20:29.919541  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c): 448 bytes on disk
I20260812 06:20:29.920135  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":67,"lbm_reads_lt_1ms":4}
I20260812 06:20:29.920607  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=3.181125
I20260812 06:20:29.940294  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.020s	user 0.013s	sys 0.004s Metrics: {"bytes_written":4512899,"delete_count":0,"lbm_write_time_us":6577,"lbm_writes_lt_1ms":113,"reinsert_count":0,"update_count":550}
I20260812 06:20:29.940714  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:29.949678  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.009s	user 0.007s	sys 0.000s Metrics: {"bytes_written":3692405,"delete_count":0,"lbm_write_time_us":3229,"lbm_writes_lt_1ms":93,"reinsert_count":0,"update_count":450}
I20260812 06:20:29.950120  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:30.112054  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.162s	user 0.118s	sys 0.043s Metrics: {"cfile_cache_miss":634,"cfile_cache_miss_bytes":28877325,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":162,"lbm_read_time_us":12566,"lbm_reads_lt_1ms":674,"lbm_write_time_us":29963,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":8448,"thread_start_us":75,"threads_started":1,"update_count":3000}
I20260812 06:20:30.112541  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:30.171299  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.059s	user 0.028s	sys 0.027s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":25056,"lbm_writes_1-10_ms":2,"lbm_writes_lt_1ms":401,"reinsert_count":0,"update_count":2000}
I20260812 06:20:30.171862  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:30.185139  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.013s	user 0.011s	sys 0.001s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4638,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.185654  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:30.337277  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.151s	user 0.114s	sys 0.027s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":862,"lbm_read_time_us":9159,"lbm_reads_lt_1ms":568,"lbm_write_time_us":29679,"lbm_writes_lt_1ms":543,"mutex_wait_us":25,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":3968,"update_count":2500}
I20260812 06:20:30.337796  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:30.383760  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.046s	user 0.025s	sys 0.012s Metrics: {"bytes_written":16409900,"delete_count":0,"lbm_write_time_us":16321,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:30.384236  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:30.400164  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.016s	user 0.011s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5905,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.400790  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:30.555039  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.154s	user 0.115s	sys 0.029s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":531,"lbm_read_time_us":10078,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29055,"lbm_writes_lt_1ms":543,"mutex_wait_us":270,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":10496,"update_count":2500}
I20260812 06:20:30.555598  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:30.596215  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.040s	user 0.027s	sys 0.011s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":16983,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:30.596743  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:30.611524  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.015s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":5439,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.612072  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:30.769681  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.157s	user 0.100s	sys 0.042s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774687,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":922,"lbm_read_time_us":9452,"lbm_reads_lt_1ms":572,"lbm_write_time_us":28787,"lbm_writes_lt_1ms":543,"mutex_wait_us":293,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":768,"update_count":2500}
I20260812 06:20:30.770256  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:30.823458  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.053s	user 0.027s	sys 0.011s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":18544,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:30.824008  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:30.838945  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.015s	user 0.008s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5438,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:30.839501  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:30.997177  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.157s	user 0.101s	sys 0.056s 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":899,"lbm_read_time_us":10445,"lbm_reads_lt_1ms":572,"lbm_write_time_us":25089,"lbm_writes_lt_1ms":543,"mutex_wait_us":272,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":8576,"update_count":2500}
I20260812 06:20:30.997711  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:31.053375  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.056s	user 0.019s	sys 0.021s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":18315,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:31.053974  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:31.195001  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.141s	user 0.096s	sys 0.040s Metrics: {"cfile_cache_miss":431,"cfile_cache_miss_bytes":20672159,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":1,"delta_iterators_relevant":1,"dirs.queue_time_us":1013,"lbm_read_time_us":10902,"lbm_reads_lt_1ms":463,"lbm_write_time_us":20801,"lbm_writes_lt_1ms":443,"mutex_wait_us":292,"peak_mem_usage":50689328,"reinsert_count":0,"spinlock_wait_cycles":16384,"update_count":2000}
I20260812 06:20:31.195515  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:31.240573  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.045s	user 0.035s	sys 0.004s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":17117,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:31.241053  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:31.256645  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.015s	user 0.013s	sys 0.002s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":6009,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.257261  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushMRSOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:31.291751  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushMRSOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.034s	user 0.028s	sys 0.001s Metrics: {"bytes_written":1275449,"cfile_init":1,"dirs.queue_time_us":73,"dirs.run_cpu_time_us":213,"dirs.run_wall_time_us":1477,"drs_written":1,"lbm_read_time_us":47,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1438,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":31}
I20260812 06:20:31.292390  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling LogGCOp(304ce1d949e54e61872489d339f74d7c): free 124257241 bytes of WAL
I20260812 06:20:31.292593  2708 log_reader.cc:385] T 304ce1d949e54e61872489d339f74d7c: removed 12 log segments from log reader
I20260812 06:20:31.292636  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000014 (ops 67-71)
I20260812 06:20:31.292663  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000015 (ops 72-76)
I20260812 06:20:31.292690  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000016 (ops 77-81)
I20260812 06:20:31.292722  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000017 (ops 82-86)
I20260812 06:20:31.292747  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000018 (ops 87-91)
I20260812 06:20:31.292778  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000019 (ops 92-96)
I20260812 06:20:31.292810  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000020 (ops 97-100)
I20260812 06:20:31.292842  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000021 (ops 101-105)
I20260812 06:20:31.292872  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000022 (ops 106-110)
I20260812 06:20:31.292904  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000023 (ops 111-115)
I20260812 06:20:31.292935  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000024 (ops 116-120)
I20260812 06:20:31.292966  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000025 (ops 121-125)
I20260812 06:20:31.314234  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: LogGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.022s	user 0.004s	sys 0.015s Metrics: {}
I20260812 06:20:31.314606  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c): 482 bytes on disk
I20260812 06:20:31.315012  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4}
I20260812 06:20:31.315522  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:31.337095  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.021s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":5714,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.337543  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:31.347352  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.010s	user 0.005s	sys 0.003s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3707,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.347949  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:31.578826  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.231s	user 0.141s	sys 0.080s Metrics: {"cfile_cache_miss":734,"cfile_cache_miss_bytes":32979753,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":4,"delta_iterators_relevant":4,"dirs.queue_time_us":543,"lbm_read_time_us":15726,"lbm_reads_lt_1ms":774,"lbm_write_time_us":34818,"lbm_writes_lt_1ms":743,"mutex_wait_us":295,"peak_mem_usage":87969044,"reinsert_count":0,"spinlock_wait_cycles":12032,"thread_start_us":91,"threads_started":1,"update_count":3500}
I20260812 06:20:31.579839  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=18.063937
I20260812 06:20:31.643862  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.064s	user 0.032s	sys 0.020s Metrics: {"bytes_written":20512315,"delete_count":0,"lbm_write_time_us":23361,"lbm_writes_lt_1ms":503,"reinsert_count":0,"update_count":2500}
I20260812 06:20:31.644296  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:31.655086  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.011s	user 0.004s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":3777,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.655565  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:31.846719  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.191s	user 0.137s	sys 0.054s 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":217,"lbm_read_time_us":11190,"lbm_reads_lt_1ms":672,"lbm_write_time_us":32888,"lbm_writes_lt_1ms":643,"mutex_wait_us":55,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":13440,"update_count":3000}
I20260812 06:20:31.847476  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:31.885758  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.038s	user 0.026s	sys 0.008s Metrics: {"bytes_written":16409905,"delete_count":0,"lbm_write_time_us":17309,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:20:31.886298  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:31.903877  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.017s	user 0.005s	sys 0.008s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4987,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:31.904325  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:32.066210  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.162s	user 0.110s	sys 0.052s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774692,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":654,"lbm_read_time_us":11583,"lbm_reads_lt_1ms":564,"lbm_write_time_us":27270,"lbm_writes_lt_1ms":543,"mutex_wait_us":42,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":4352,"update_count":2500}
I20260812 06:20:32.066794  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:32.118451  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.051s	user 0.015s	sys 0.034s Metrics: {"bytes_written":16409903,"delete_count":0,"lbm_write_time_us":18620,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:32.119093  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:32.135179  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.016s	user 0.014s	sys 0.000s Metrics: {"bytes_written":4102658,"delete_count":0,"lbm_write_time_us":6115,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.136008  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:32.317143  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.181s	user 0.101s	sys 0.069s 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":584,"lbm_read_time_us":13229,"lbm_reads_lt_1ms":572,"lbm_write_time_us":29182,"lbm_writes_lt_1ms":543,"mutex_wait_us":54,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":13824,"update_count":2500}
I20260812 06:20:32.317677  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:32.377562  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.060s	user 0.033s	sys 0.019s Metrics: {"bytes_written":16409901,"delete_count":0,"lbm_write_time_us":19650,"lbm_writes_lt_1ms":403,"reinsert_count":0,"update_count":2000}
I20260812 06:20:32.378248  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:32.389355  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.011s	user 0.005s	sys 0.004s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4152,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.389863  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:32.581858  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.192s	user 0.109s	sys 0.070s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774688,"cfile_init":10,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":3535,"lbm_read_time_us":14184,"lbm_reads_lt_1ms":572,"lbm_write_time_us":30316,"lbm_writes_lt_1ms":543,"mutex_wait_us":1608,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":8576,"update_count":2500}
I20260812 06:20:32.582590  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=14.095187
I20260812 06:20:32.637017  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.054s	user 0.018s	sys 0.035s Metrics: {"bytes_written":16409904,"delete_count":0,"lbm_write_time_us":20927,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":402,"reinsert_count":0,"update_count":2000}
I20260812 06:20:32.637745  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=2.188937
I20260812 06:20:32.648888  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.011s	user 0.005s	sys 0.005s Metrics: {"bytes_written":4102659,"delete_count":0,"lbm_write_time_us":4132,"lbm_writes_lt_1ms":103,"reinsert_count":0,"update_count":500}
I20260812 06:20:32.649521  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushMRSOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:32.680873  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushMRSOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.031s	user 0.021s	sys 0.006s Metrics: {"bytes_written":1152510,"cfile_init":1,"dirs.queue_time_us":84,"dirs.run_cpu_time_us":305,"dirs.run_wall_time_us":1562,"drs_written":1,"lbm_read_time_us":52,"lbm_reads_lt_1ms":4,"lbm_write_time_us":1524,"lbm_writes_lt_1ms":39,"peak_mem_usage":0,"rows_written":28}
I20260812 06:20:32.681674  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c): 447 bytes on disk
I20260812 06:20:32.682160  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: UndoDeltaBlockGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.000s	user 0.000s	sys 0.000s Metrics: {"cfile_init":1,"lbm_read_time_us":60,"lbm_reads_lt_1ms":4}
I20260812 06:20:32.682801  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:32.860097  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.177s	user 0.138s	sys 0.039s Metrics: {"cfile_cache_miss":532,"cfile_cache_miss_bytes":24774691,"cfile_init":8,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"dirs.queue_time_us":292,"lbm_read_time_us":11570,"lbm_reads_lt_1ms":564,"lbm_write_time_us":30864,"lbm_writes_lt_1ms":543,"mutex_wait_us":76,"peak_mem_usage":63115900,"reinsert_count":0,"spinlock_wait_cycles":14848,"update_count":2500}
I20260812 06:20:32.860773  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling LogGCOp(304ce1d949e54e61872489d339f74d7c): free 124710550 bytes of WAL
I20260812 06:20:32.861016  2708 log_reader.cc:385] T 304ce1d949e54e61872489d339f74d7c: removed 12 log segments from log reader
I20260812 06:20:32.861060  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000026 (ops 126-130)
I20260812 06:20:32.861100  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000027 (ops 131-135)
I20260812 06:20:32.861135  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000028 (ops 136-140)
I20260812 06:20:32.861186  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000029 (ops 141-145)
I20260812 06:20:32.861218  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000030 (ops 146-150)
I20260812 06:20:32.861279  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000031 (ops 151-155)
I20260812 06:20:32.861312  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000032 (ops 156-160)
I20260812 06:20:32.861367  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000033 (ops 161-165)
I20260812 06:20:32.861402  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000034 (ops 166-170)
I20260812 06:20:32.861455  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000035 (ops 171-175)
I20260812 06:20:32.861490  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000036 (ops 176-180)
I20260812 06:20:32.861544  2708 log.cc:1079] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: Deleting log segment in path: /tmp/dist-test-taskeIpFAg/test-tmp/heavy-update-compaction-itest.0.EvictionPolicyTypes_HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction_1.1786515622818247-2242-0/minicluster-data/ts-0-root/wals/304ce1d949e54e61872489d339f74d7c/wal-000000037 (ops 181-185)
I20260812 06:20:32.887017  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: LogGCOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.026s	user 0.000s	sys 0.023s Metrics: {}
I20260812 06:20:32.887627  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=15.087375
I20260812 06:20:32.959829  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.072s	user 0.034s	sys 0.027s Metrics: {"bytes_written":16820142,"delete_count":0,"lbm_write_time_us":24170,"lbm_writes_1-10_ms":1,"lbm_writes_lt_1ms":412,"reinsert_count":0,"update_count":2050}
I20260812 06:20:32.960372  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=6.157687
I20260812 06:20:32.979296  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.019s	user 0.012s	sys 0.004s Metrics: {"bytes_written":7794836,"delete_count":0,"lbm_write_time_us":7387,"lbm_writes_lt_1ms":193,"reinsert_count":0,"update_count":950}
I20260812 06:20:32.979889  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c): perf score=1.000000
I20260812 06:20:33.120316  2242 heavy-update-compaction-itest.cc:229] Time spent updating: real 4.718s	user 1.735s	sys 0.155s
I20260812 06:20:33.169370  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: MajorDeltaCompactionOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.189s	user 0.126s	sys 0.061s Metrics: {"cfile_cache_miss":632,"cfile_cache_miss_bytes":28877100,"cfile_init":9,"delete_count":0,"delta_blocks_compacted":2,"delta_iterators_relevant":2,"lbm_read_time_us":13822,"lbm_reads_lt_1ms":668,"lbm_write_time_us":33191,"lbm_writes_lt_1ms":643,"peak_mem_usage":75542472,"reinsert_count":0,"spinlock_wait_cycles":1280,"update_count":3000}
I20260812 06:20:33.169893  2818 maintenance_manager.cc:419] P 5eefc7a0693e4e37a06ca37f5b6102ed: Scheduling FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c): perf score=10.126437
I20260812 06:20:33.195248  2242 heavy-update-compaction-itest.cc:265] Time spent scanning: real 0.074s	user 0.001s	sys 0.000s
I20260812 06:20:33.195842  2242 tablet_server.cc:179] TabletServer@127.2.48.129:0 shutting down...
I20260812 06:20:33.201151  2708 maintenance_manager.cc:643] P 5eefc7a0693e4e37a06ca37f5b6102ed: FlushDeltaMemStoresOp(304ce1d949e54e61872489d339f74d7c) complete. Timing: real 0.031s	user 0.022s	sys 0.007s Metrics: {"bytes_written":12307490,"delete_count":0,"lbm_write_time_us":12741,"lbm_writes_lt_1ms":303,"reinsert_count":0,"update_count":1500}
I20260812 06:20:33.202064  2242 ts_tablet_manager.cc:1507] Shutting down tablet manager...
I20260812 06:20:33.202288  2242 tablet_replica.cc:333] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed: stopping tablet replica
I20260812 06:20:33.202419  2242 raft_consensus.cc:2243] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:33.202590  2242 raft_consensus.cc:2272] T 304ce1d949e54e61872489d339f74d7c P 5eefc7a0693e4e37a06ca37f5b6102ed [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:33.206120  2242 tablet_server.cc:196] TabletServer@127.2.48.129:0 shutdown complete.
I20260812 06:20:33.228515  2242 master.cc:562] Master@127.2.48.190:45473 shutting down...
I20260812 06:20:33.231532  2242 raft_consensus.cc:2243] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 1 LEADER]: Raft consensus shutting down.
I20260812 06:20:33.231782  2242 raft_consensus.cc:2272] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc [term 1 FOLLOWER]: Raft consensus is shut down!
I20260812 06:20:33.231858  2242 tablet_replica.cc:333] T 00000000000000000000000000000000 P d6ce4617999548948f178f8ca988cabc: stopping tablet replica
I20260812 06:20:33.244490  2242 master.cc:584] Master@127.2.48.190:45473 shutdown complete.
[       OK ] EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy.TestHeavyUpdateCompaction/1 (5130 ms)
[----------] 2 tests from EvictionPolicyTypes/HeavyUpdateCompactionITestWithEvictionPolicy (10496 ms total)

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