JUnit version 4.13.2
.23:47:43.349 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1783468063340
23:47:43.372 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
23:47:43.414 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
23:47:43.425 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskMi4vCK/test-tmp
23:47:43.458 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/asan/bin
23:47:43.461 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskMi4vCK/java/build/dist-test/../../../build/asan/bin/kudu, test, mini_cluster, --serialization=pb]
23:47:43.701 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

23:47:43.916 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
23:47:43.917 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

23:47:43.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:43.921705 32204 mini_oidc.cc:150] Starting JWKS server
23:47:43.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:43.925076 32204 webserver.cc:534] Webserver started at http://127.0.0.1:37857/ using document root <none> and password file <none>
23:47:43.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:43.925405 32204 mini_oidc.cc:186] Starting OIDC Discovery server
23:47:43.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:43.926599 32204 webserver.cc:534] Webserver started at http://127.0.0.1:37677/ using document root <none> and password file <none>
23:47:43.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:47:43Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
23:47:43.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:47:43Z Disabled control of system clock
23:47:43.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:43.966037 32204 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:43.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:43.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/wal
23:47:43.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data
23:47:43.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:47:43.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:47:43.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:47:43.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:43.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:43.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:43.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/logs
23:47:43.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data/info.pb
23:47:43.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:43.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:47:43.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:47:43.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:47:43.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:47:43.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:43.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:43.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:43.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:47:43.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:47:43.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:47:43.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:47:43.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.62:40183
23:47:43.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.62
23:47:43.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:43.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:43.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:43.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:43.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:43.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:47:43.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:43.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:47:43.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37857/jwks.json with env {}
23:47:44.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.300794 32215 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:47:44.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.301162 32215 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:47:44.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.301275 32215 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:47:44.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.310065 32215 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
23:47:44.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.310195 32215 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:47:44.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.310279 32215 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
23:47:44.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.310336 32215 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
23:47:44.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.310408 32215 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
23:47:44.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.310458 32215 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37857/jwks.json
23:47:44.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.321142 32215 master_runner.cc:387] Master server non-default flags:
23:47:44.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:44.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:44.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:44.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:44.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data
23:47:44.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/wal
23:47:44.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:47:44.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:44.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:44.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:44.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:47:44.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.62:40183
23:47:44.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:47:44.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:47:44.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:47:44.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37857/jwks.json
23:47:44.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:44.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:44.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data/info.pb
23:47:44.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.62
23:47:44.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:44.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:47:44.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:44.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:47:44.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:47:44.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:44.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/logs
23:47:44.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:44.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:47:44.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:47:44.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:47:44.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:47:44.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:47:44.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:47:44.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on bdcb31816ec0
23:47:44.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16835
23:47:44.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
23:47:44.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.323570 32215 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:47:44.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.326261 32215 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:47:44.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.336653 32221 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
23:47:44.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.336755 32223 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
23:47:44.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.336730 32220 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
23:47:44.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.337438 32215 server_base.cc:1061] running on GCE node
23:47:44.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.340209 32215 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:47:44.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.341900 32215 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:47:44.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.343111 32215 hybrid_clock.cc:648] HybridClock initialized: now 1783468064343050 us; error 59 us; skew 500 ppm
23:47:44.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.343488 32215 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:47:44.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.346051 32215 webserver.cc:534] Webserver started at http://127.31.115.62:33203/ using document root <none> and password file <none>
23:47:44.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.346803 32215 fs_manager.cc:362] Metadata directory not provided
23:47:44.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.346897 32215 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:47:44.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.347303 32215 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:47:44.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.349891 32215 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data/instance:
23:47:44.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "de428f0f07664efeb9fe7fe4013bac89"
23:47:44.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:44 on dist-test-slave-66bt"
23:47:44.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.350899 32215 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/wal/instance:
23:47:44.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "de428f0f07664efeb9fe7fe4013bac89"
23:47:44.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:44 on dist-test-slave-66bt"
23:47:44.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.357138 32215 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.005s	sys 0.001s
23:47:44.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.360679 32229 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:47:44.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.362239 32215 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.002s
23:47:44.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.362424 32215 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data,/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/wal
23:47:44.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "de428f0f07664efeb9fe7fe4013bac89"
23:47:44.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:44 on dist-test-slave-66bt"
23:47:44.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.362623 32215 fs_report.cc:389] FS layout report
23:47:44.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:47:44.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/wal
23:47:44.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/wal
23:47:44.364 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data/data
23:47:44.364 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:47:44.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:47:44.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:47:44.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:47:44.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:47:44.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:47:44.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:47:44.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:47:44.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:47:44.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:47:44.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:47:44.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.382220 32215 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:47:44.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.383131 32215 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
23:47:44.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.383363 32215 kserver.cc:163] Server-wide thread pool size limit: 3276
23:47:44.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.402980 32215 rpc_server.cc:307] RPC server started. Bound to: 127.31.115.62:40183
23:47:44.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.402997 32280 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.115.62:40183 every 8 connection(s)
23:47:44.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.404610 32215 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/data/info.pb
23:47:44.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.408905 32281 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
23:47:44.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.414187 32204 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu as pid 32215
23:47:44.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.414453 32204 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-0/wal/instance
23:47:44.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.414965 32281 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } has no permanent_uuid. Determining permanent_uuid...
23:47:44.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.416756 32204 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:44.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:44.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/wal
23:47:44.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data
23:47:44.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:47:44.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:47:44.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:47:44.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:44.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:44.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:44.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/logs
23:47:44.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data/info.pb
23:47:44.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:44.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:47:44.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:47:44.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:47:44.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:47:44.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:47:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:47:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:47:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:47:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.61:45795
23:47:44.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.61
23:47:44.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:44.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:44.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:44.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:44.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:44.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:47:44.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:44.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:47:44.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37857/jwks.json with env {}
23:47:44.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.439806 32281 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } has no permanent_uuid. Determining permanent_uuid...
23:47:44.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.441186 32231 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.31.115.61:45795: connect: Connection refused (error 111)
23:47:44.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.442901 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.61:45795: Network error: Client connection negotiation failed: client connection to 127.31.115.61:45795: connect: Connection refused (error 111)
23:47:44.492 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.492293 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } attempt: 1
23:47:44.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.494652 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.61:45795: Network error: Client connection negotiation failed: client connection to 127.31.115.61:45795: connect: Connection refused (error 111)
23:47:44.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.562912 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } attempt: 2
23:47:44.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.565157 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.61:45795: Network error: Client connection negotiation failed: client connection to 127.31.115.61:45795: connect: Connection refused (error 111)
23:47:44.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.656416 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } attempt: 3
23:47:44.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.658818 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.61:45795: Network error: Client connection negotiation failed: client connection to 127.31.115.61:45795: connect: Connection refused (error 111)
23:47:44.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.785120 32284 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:47:44.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.785502 32284 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:47:44.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.785635 32284 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:47:44.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.794396 32284 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
23:47:44.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.794545 32284 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:47:44.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.794624 32284 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
23:47:44.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.794695 32284 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
23:47:44.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.794770 32284 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
23:47:44.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.794821 32284 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37857/jwks.json
23:47:44.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.802126 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } attempt: 4
23:47:44.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.804423 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.61:45795: Network error: Client connection negotiation failed: client connection to 127.31.115.61:45795: connect: Connection refused (error 111)
23:47:44.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.806732 32284 master_runner.cc:387] Master server non-default flags:
23:47:44.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:44.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:44.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:44.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:44.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data
23:47:44.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/wal
23:47:44.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:47:44.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:44.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:44.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:44.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:47:44.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.61:45795
23:47:44.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:47:44.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:47:44.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:47:44.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37857/jwks.json
23:47:44.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:44.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:44.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data/info.pb
23:47:44.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.61
23:47:44.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:44.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:47:44.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:44.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:47:44.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:47:44.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:44.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/logs
23:47:44.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:44.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:47:44.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:47:44.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:47:44.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:47:44.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:47:44.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:47:44.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on bdcb31816ec0
23:47:44.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16835
23:47:44.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
23:47:44.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.809182 32284 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:47:44.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.811971 32284 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:47:44.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.821147 32292 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
23:47:44.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.821193 32291 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
23:47:44.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.822784 32294 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
23:47:44.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.824687 32284 server_base.cc:1061] running on GCE node
23:47:44.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.825659 32284 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:47:44.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.827451 32284 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:47:44.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.828698 32284 hybrid_clock.cc:648] HybridClock initialized: now 1783468064828636 us; error 53 us; skew 500 ppm
23:47:44.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.829180 32284 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:47:44.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.831812 32284 webserver.cc:534] Webserver started at http://127.31.115.61:33593/ using document root <none> and password file <none>
23:47:44.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.832695 32284 fs_manager.cc:362] Metadata directory not provided
23:47:44.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.832832 32284 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:47:44.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.833302 32284 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:47:44.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.836052 32284 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data/instance:
23:47:44.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "7363ada4436c41df8e0ac7dc0fee56fb"
23:47:44.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:44 on dist-test-slave-66bt"
23:47:44.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.837102 32284 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/wal/instance:
23:47:44.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "7363ada4436c41df8e0ac7dc0fee56fb"
23:47:44.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:44 on dist-test-slave-66bt"
23:47:44.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.843138 32284 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.006s	sys 0.001s
23:47:44.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.847054 32300 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:47:44.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.849135 32284 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.004s	sys 0.000s
23:47:44.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.849350 32284 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data,/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/wal
23:47:44.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "7363ada4436c41df8e0ac7dc0fee56fb"
23:47:44.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:44 on dist-test-slave-66bt"
23:47:44.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.849612 32284 fs_report.cc:389] FS layout report
23:47:44.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:47:44.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/wal
23:47:44.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/wal
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data/data
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:47:44.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:47:44.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:47:44.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:47:44.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:47:44.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:47:44.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.868556 32284 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:47:44.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.869699 32284 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
23:47:44.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.870080 32284 kserver.cc:163] Server-wide thread pool size limit: 3276
23:47:44.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.893075 32284 rpc_server.cc:307] RPC server started. Bound to: 127.31.115.61:45795
23:47:44.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.893095 32351 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.115.61:45795 every 8 connection(s)
23:47:44.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.894979 32284 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/data/info.pb
23:47:44.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.896241 32204 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu as pid 32284
23:47:44.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.896438 32204 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-1/wal/instance
23:47:44.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.897624 32204 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:44.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:44.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/wal
23:47:44.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data
23:47:44.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:47:44.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:47:44.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:47:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/logs
23:47:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data/info.pb
23:47:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:47:44.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:47:44.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:47:44.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:47:44.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.60:39357
23:47:44.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.60
23:47:44.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:44.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:44.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:44.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:44.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:44.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:47:44.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:44.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:47:44.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37857/jwks.json with env {}
23:47:44.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.906426 32352 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
23:47:44.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.915050 32352 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } has no permanent_uuid. Determining permanent_uuid...
23:47:44.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.929469 32352 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } has no permanent_uuid. Determining permanent_uuid...
23:47:44.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.939327 32352 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } has no permanent_uuid. Determining permanent_uuid...
23:47:44.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.940526 32301 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:44.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.942274 32352 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:44.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:44.991556 32352 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 1
23:47:44.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:44.993610 32352 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.061892 32352 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 2
23:47:45.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.064211 32352 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.103683 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } attempt: 5
23:47:45.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.110889 32281 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } has no permanent_uuid. Determining permanent_uuid...
23:47:45.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.112567 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.155467 32352 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 3
23:47:45.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.157737 32352 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.163818 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 1
23:47:45.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.165899 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.234163 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 2
23:47:45.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.236289 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.246698 32354 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:47:45.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.247044 32354 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:47:45.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.247161 32354 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:47:45.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.255749 32354 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
23:47:45.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.255870 32354 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:47:45.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.255951 32354 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
23:47:45.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.256011 32354 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
23:47:45.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.256085 32354 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
23:47:45.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.256134 32354 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37857/jwks.json
23:47:45.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.266166 32354 master_runner.cc:387] Master server non-default flags:
23:47:45.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:45.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/wal
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:45.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.60:39357
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37857/jwks.json
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:45.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data/info.pb
23:47:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.60
23:47:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:47:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:47:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:47:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/logs
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:47:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:47:45.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on bdcb31816ec0
23:47:45.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16835
23:47:45.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
23:47:45.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.268559 32354 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:47:45.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.271152 32354 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:47:45.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.279776 32365 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
23:47:45.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.279758 32362 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
23:47:45.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.280369 32363 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
23:47:45.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.280732 32354 server_base.cc:1061] running on GCE node
23:47:45.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.281595 32354 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:47:45.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.283085 32354 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:47:45.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.284329 32354 hybrid_clock.cc:648] HybridClock initialized: now 1783468065284271 us; error 35 us; skew 500 ppm
23:47:45.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.284750 32354 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:47:45.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.287045 32354 webserver.cc:534] Webserver started at http://127.31.115.60:42205/ using document root <none> and password file <none>
23:47:45.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.287837 32354 fs_manager.cc:362] Metadata directory not provided
23:47:45.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.287963 32354 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:47:45.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.288473 32354 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:47:45.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.290959 32354 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data/instance:
23:47:45.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5ba241becf114f6cbf5388a9c0ea0c19"
23:47:45.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:45 on dist-test-slave-66bt"
23:47:45.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.291951 32354 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/wal/instance:
23:47:45.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5ba241becf114f6cbf5388a9c0ea0c19"
23:47:45.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:45 on dist-test-slave-66bt"
23:47:45.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.297636 32354 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.002s	sys 0.005s
23:47:45.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.300969 32352 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 4
23:47:45.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.301270 32371 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:47:45.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.303301 32352 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.304222 32354 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.003s	sys 0.000s
23:47:45.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.304399 32354 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data,/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/wal
23:47:45.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5ba241becf114f6cbf5388a9c0ea0c19"
23:47:45.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:45 on dist-test-slave-66bt"
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.304632 32354 fs_report.cc:389] FS layout report
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/wal
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/wal
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data/data
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:47:45.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:47:45.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:47:45.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:47:45.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:47:45.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:47:45.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:47:45.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:47:45.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.331980 32354 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:47:45.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.333089 32354 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
23:47:45.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.333331 32354 kserver.cc:163] Server-wide thread pool size limit: 3276
23:47:45.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.342526 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 3
23:47:45.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.344897 32281 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.31.115.60:39357: Network error: Client connection negotiation failed: client connection to 127.31.115.60:39357: connect: Connection refused (error 111)
23:47:45.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.355170 32354 rpc_server.cc:307] RPC server started. Bound to: 127.31.115.60:39357
23:47:45.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.355211 32422 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.115.60:39357 every 8 connection(s)
23:47:45.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.356724 32354 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/data/info.pb
23:47:45.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.360941 32423 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
23:47:45.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.366268 32204 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu as pid 32354
23:47:45.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.366421 32204 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/master-2/wal/instance
23:47:45.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.367674 32423 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } has no permanent_uuid. Determining permanent_uuid...
23:47:45.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.383913 32423 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } has no permanent_uuid. Determining permanent_uuid...
23:47:45.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.392066 32423 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } has no permanent_uuid. Determining permanent_uuid...
23:47:45.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.402756 32423 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19: Bootstrap starting.
23:47:45.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.406391 32423 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19: Neither blocks nor log segments found. Creating new log.
23:47:45.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.407536 32423 log.cc:826] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19: Log is configured to *not* fsync() on all Append() calls
23:47:45.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.410847 32423 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19: No bootstrap required, opened a new log
23:47:45.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.416524 32423 raft_consensus.cc:359] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.416817 32423 raft_consensus.cc:385] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:47:45.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.416931 32423 raft_consensus.cc:740] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5ba241becf114f6cbf5388a9c0ea0c19, State: Initialized, Role: FOLLOWER
23:47:45.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.417625 32423 consensus_queue.cc:260] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [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: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.418560 32432 sys_catalog.cc:455] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.418787 32432 sys_catalog.cc:458] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: This master's current role is: FOLLOWER
23:47:45.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.419595 32423 sys_catalog.cc:565] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: configured and running, proceeding with master startup.
23:47:45.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.432866 32443 catalog_manager.cc:1593] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
23:47:45.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.433000 32443 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
23:47:45.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.429042 32354 master_runner.cc:187] Error getting master registration for 127.31.115.62:40183: OK, instance_id { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" instance_seqno: 1783468064397258 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
23:47:45.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.436302 32354 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
23:47:45.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.522130 32281 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 4
23:47:45.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.535359 32281 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: Bootstrap starting.
23:47:45.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.539714 32281 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: Neither blocks nor log segments found. Creating new log.
23:47:45.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.541256 32281 log.cc:826] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: Log is configured to *not* fsync() on all Append() calls
23:47:45.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.544476 32281 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: No bootstrap required, opened a new log
23:47:45.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.552379 32281 raft_consensus.cc:359] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.552729 32281 raft_consensus.cc:385] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:47:45.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.552840 32281 raft_consensus.cc:740] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: de428f0f07664efeb9fe7fe4013bac89, State: Initialized, Role: FOLLOWER
23:47:45.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.553633 32281 consensus_queue.cc:260] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [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: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.555063 32446 sys_catalog.cc:455] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.555639 32446 sys_catalog.cc:458] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [sys.catalog]: This master's current role is: FOLLOWER
23:47:45.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.556473 32281 sys_catalog.cc:565] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [sys.catalog]: configured and running, proceeding with master startup.
23:47:45.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.567270 32457 catalog_manager.cc:1593] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
23:47:45.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.567915 32457 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
23:47:45.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.565748 32215 master_runner.cc:187] Error getting master registration for 127.31.115.61:45795: OK, instance_id { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" instance_seqno: 1783468064887188 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
23:47:45.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.572022 32215 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
23:47:45.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.585520 32377 tablet.cc:2406] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:47:45.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.602512 32352 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } attempt: 5
23:47:45.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.612684 32352 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: Bootstrap starting.
23:47:45.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.616881 32352 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: Neither blocks nor log segments found. Creating new log.
23:47:45.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.618170 32432 raft_consensus.cc:493] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
23:47:45.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.618445 32432 raft_consensus.cc:515] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.618716 32352 log.cc:826] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: Log is configured to *not* fsync() on all Append() calls
23:47:45.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.620649 32432 leader_election.cc:290] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers de428f0f07664efeb9fe7fe4013bac89 (127.31.115.62:40183), 7363ada4436c41df8e0ac7dc0fee56fb (127.31.115.61:45795)
23:47:45.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.621855 32256 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "de428f0f07664efeb9fe7fe4013bac89" is_pre_election: true
23:47:45.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.622540 32256 raft_consensus.cc:2468] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 5ba241becf114f6cbf5388a9c0ea0c19 in term 0.
23:47:45.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.623307 32372 leader_election.cc:304] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [CANDIDATE]: Term 1 pre-election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 5ba241becf114f6cbf5388a9c0ea0c19, de428f0f07664efeb9fe7fe4013bac89; no voters: 
23:47:45.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.624540 32432 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Leader pre-election won for term 1
23:47:45.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.624681 32432 raft_consensus.cc:493] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
23:47:45.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.624780 32432 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 0 FOLLOWER]: Advancing to term 1
23:47:45.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.628630 32432 raft_consensus.cc:515] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.630089 32256 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "de428f0f07664efeb9fe7fe4013bac89"
23:47:45.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.630354 32256 raft_consensus.cc:3060] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [term 0 FOLLOWER]: Advancing to term 1
23:47:45.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.636128 32235 tablet.cc:2406] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:47:45.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.645519 32432 leader_election.cc:290] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [CANDIDATE]: Term 1 election: Requested vote from peers de428f0f07664efeb9fe7fe4013bac89 (127.31.115.62:40183), 7363ada4436c41df8e0ac7dc0fee56fb (127.31.115.61:45795)
23:47:45.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.651453 32352 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: No bootstrap required, opened a new log
23:47:45.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.663408 32352 raft_consensus.cc:359] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.664358 32352 raft_consensus.cc:385] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:47:45.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.664791 32352 raft_consensus.cc:740] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 7363ada4436c41df8e0ac7dc0fee56fb, State: Initialized, Role: FOLLOWER
23:47:45.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.665697 32352 consensus_queue.cc:260] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [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: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.678666 32460 sys_catalog.cc:455] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.679229 32460 sys_catalog.cc:458] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [sys.catalog]: This master's current role is: FOLLOWER
23:47:45.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.680202 32352 sys_catalog.cc:565] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [sys.catalog]: configured and running, proceeding with master startup.
23:47:45.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.680228 32327 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" is_pre_election: true
23:47:45.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.681442 32327 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 5ba241becf114f6cbf5388a9c0ea0c19 in term 0.
23:47:45.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.680274 32326 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "7363ada4436c41df8e0ac7dc0fee56fb"
23:47:45.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.687623 32326 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [term 0 FOLLOWER]: Advancing to term 1
23:47:45.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.727289 32256 raft_consensus.cc:2468] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 5ba241becf114f6cbf5388a9c0ea0c19 in term 1.
23:47:45.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.728924 32372 leader_election.cc:304] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 5ba241becf114f6cbf5388a9c0ea0c19, de428f0f07664efeb9fe7fe4013bac89; no voters: 
23:47:45.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.729456 32432 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 1 FOLLOWER]: Leader election won for term 1
23:47:45.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.730334 32326 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 5ba241becf114f6cbf5388a9c0ea0c19 in term 1.
23:47:45.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.733194 32432 raft_consensus.cc:697] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [term 1 LEADER]: Becoming Leader. State: Replica: 5ba241becf114f6cbf5388a9c0ea0c19, State: Running, Role: LEADER
23:47:45.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.734017 32432 consensus_queue.cc:237] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [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: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } }
23:47:45.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.758311 32473 catalog_manager.cc:1593] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
23:47:45.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:45.759043 32473 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
23:47:45.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.759346 32471 sys_catalog.cc:455] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 5ba241becf114f6cbf5388a9c0ea0c19. Latest consensus state: current_term: 1 leader_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.759650 32471 sys_catalog.cc:458] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: This master's current role is: LEADER
23:47:45.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.760784 32475 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
23:47:45.763 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.763433 32475 catalog_manager.cc:1519] Initializing Kudu cluster ID...
23:47:45.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.784560 32256 raft_consensus.cc:1275] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [term 1 FOLLOWER]: Refusing update from remote peer 5ba241becf114f6cbf5388a9c0ea0c19: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:47:45.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.785035 32326 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [term 1 FOLLOWER]: Refusing update from remote peer 5ba241becf114f6cbf5388a9c0ea0c19: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:47:45.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.786368 32432 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [LEADER]: Connected to new peer: Peer: permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
23:47:45.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.790134 32432 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [LEADER]: Connected to new peer: Peer: permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
23:47:45.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.821437 32446 sys_catalog.cc:455] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 5ba241becf114f6cbf5388a9c0ea0c19. Latest consensus state: current_term: 1 leader_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.821738 32446 sys_catalog.cc:458] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [sys.catalog]: This master's current role is: FOLLOWER
23:47:45.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.826761 32446 sys_catalog.cc:455] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.827010 32446 sys_catalog.cc:458] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89 [sys.catalog]: This master's current role is: FOLLOWER
23:47:45.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.827282 32471 sys_catalog.cc:455] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.827505 32471 sys_catalog.cc:458] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: This master's current role is: LEADER
23:47:45.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.827767 32476 mvcc.cc:204] Tried to move back new op lower bound from 7305085197437136896 to 7305085197259051008. Current Snapshot: MvccSnapshot[applied={T|T < 7305085197437136896}]
23:47:45.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.834182 32478 mvcc.cc:204] Tried to move back new op lower bound from 7305085197437136896 to 7305085197259051008. Current Snapshot: MvccSnapshot[applied={T|T < 7305085197437136896}]
23:47:45.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.838636 32475 catalog_manager.cc:1382] Generated new cluster ID: a91f36f36c2545129b267ec5a44ff613
23:47:45.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.838755 32475 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
23:47:45.854 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.853768 32460 sys_catalog.cc:455] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [sys.catalog]: SysCatalogTable state changed. Reason: New leader 5ba241becf114f6cbf5388a9c0ea0c19. Latest consensus state: current_term: 1 leader_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.854 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.854393 32460 sys_catalog.cc:458] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [sys.catalog]: This master's current role is: FOLLOWER
23:47:45.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.857163 32432 sys_catalog.cc:455] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.857401 32432 sys_catalog.cc:458] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 [sys.catalog]: This master's current role is: LEADER
23:47:45.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.860240 32477 mvcc.cc:204] Tried to move back new op lower bound from 7305085197437136896 to 7305085197259051008. Current Snapshot: MvccSnapshot[applied={T|T < 7305085197437136896}]
23:47:45.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.869549 32460 sys_catalog.cc:455] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "de428f0f07664efeb9fe7fe4013bac89" member_type: VOTER last_known_addr { host: "127.31.115.62" port: 40183 } } peers { permanent_uuid: "7363ada4436c41df8e0ac7dc0fee56fb" member_type: VOTER last_known_addr { host: "127.31.115.61" port: 45795 } } peers { permanent_uuid: "5ba241becf114f6cbf5388a9c0ea0c19" member_type: VOTER last_known_addr { host: "127.31.115.60" port: 39357 } } }
23:47:45.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.869843 32460 sys_catalog.cc:458] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb [sys.catalog]: This master's current role is: FOLLOWER
23:47:45.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.878167 32475 catalog_manager.cc:1405] Generated new certificate authority record
23:47:45.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.881875 32475 catalog_manager.cc:1539] Loading token signing keys...
23:47:45.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.944660 32475 catalog_manager.cc:6069] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19: Generated new TSK 0
23:47:45.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.946741 32475 catalog_manager.cc:1549] Initializing in-progress tserver states...
23:47:45.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:45.980518 32204 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:45.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/wal
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/logs
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data/info.pb
23:47:45.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.1:0
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.115.1
23:47:45.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.1
23:47:45.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
23:47:46.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.451305 32484 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:47:46.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.451615 32484 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:47:46.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.451771 32484 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:47:46.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.461140 32484 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:47:46.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.461297 32484 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.115.1
23:47:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.471968 32484 tablet_server_runner.cc:78] Tablet server non-default flags:
23:47:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data
23:47:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/wal
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.1:0
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data/info.pb
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.1
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:46.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.115.1
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/logs
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:47:46.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on bdcb31816ec0
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16835
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.473723 32484 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:47:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.475627 32484 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:47:46.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.487321 32484 server_base.cc:1061] running on GCE node
23:47:46.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.486765 32489 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
23:47:46.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.486721 32490 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
23:47:46.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.488227 32492 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
23:47:46.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.488940 32484 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:47:46.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.490156 32484 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:47:46.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.491382 32484 hybrid_clock.cc:648] HybridClock initialized: now 1783468066491334 us; error 41 us; skew 500 ppm
23:47:46.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.491775 32484 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:47:46.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.494271 32484 webserver.cc:534] Webserver started at http://127.31.115.1:42741/ using document root <none> and password file <none>
23:47:46.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.495163 32484 fs_manager.cc:362] Metadata directory not provided
23:47:46.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.495293 32484 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:47:46.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.495738 32484 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:47:46.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.498350 32484 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data/instance:
23:47:46.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f3ab9d46dcb948a7acd267245637ff1c"
23:47:46.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:46 on dist-test-slave-66bt"
23:47:46.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.499259 32484 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/wal/instance:
23:47:46.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f3ab9d46dcb948a7acd267245637ff1c"
23:47:46.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:46 on dist-test-slave-66bt"
23:47:46.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.506700 32484 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.007s	sys 0.001s
23:47:46.510 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.510545 32500 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:47:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.512064 32484 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.001s
23:47:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.512255 32484 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data,/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/wal
23:47:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f3ab9d46dcb948a7acd267245637ff1c"
23:47:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:46 on dist-test-slave-66bt"
23:47:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.512507 32484 fs_report.cc:389] FS layout report
23:47:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/wal
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/wal
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data/data
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:47:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:47:46.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:47:46.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.532464 32484 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:47:46.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.533521 32484 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
23:47:46.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.533882 32484 kserver.cc:163] Server-wide thread pool size limit: 3276
23:47:46.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.535082 32484 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
23:47:46.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.536823 32484 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:47:46.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.536947 32484 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:47:46.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.537052 32484 ts_tablet_manager.cc:616] Registered 0 tablets
23:47:46.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.537127 32484 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
23:47:46.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.571103 32457 catalog_manager.cc:1294] Loaded cluster ID: a91f36f36c2545129b267ec5a44ff613
23:47:46.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.571247 32457 catalog_manager.cc:1587] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: loading cluster ID for follower catalog manager: success
23:47:46.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.574915 32457 catalog_manager.cc:1609] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: acquiring CA information for follower catalog manager: success
23:47:46.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.577382 32457 catalog_manager.cc:1637] T 00000000000000000000000000000000 P de428f0f07664efeb9fe7fe4013bac89: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
23:47:46.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.578904 32484 rpc_server.cc:307] RPC server started. Bound to: 127.31.115.1:33365
23:47:46.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.578953 32612 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.115.1:33365 every 8 connection(s)
23:47:46.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.580479 32484 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/data/info.pb
23:47:46.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.586664 32204 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu as pid 32484
23:47:46.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.586884 32204 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-0/wal/instance
23:47:46.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.593237 32204 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:46.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:46.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/wal
23:47:46.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/logs
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data/info.pb
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:46.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.2:0
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.115.2
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.2
23:47:46.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:46.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:46.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:46.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:46.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:46.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
23:47:46.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.604877 32615 heartbeater.cc:344] Connected to a master server at 127.31.115.61:45795
23:47:46.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.605324 32615 heartbeater.cc:461] Registering TS with master...
23:47:46.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.606590 32615 heartbeater.cc:507] Master 127.31.115.61:45795 requested a full tablet report, sending...
23:47:46.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.607270 32614 heartbeater.cc:344] Connected to a master server at 127.31.115.62:40183
23:47:46.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.607401 32614 heartbeater.cc:461] Registering TS with master...
23:47:46.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.608352 32614 heartbeater.cc:507] Master 127.31.115.62:40183 requested a full tablet report, sending...
23:47:46.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.609802 32317 ts_manager.cc:194] Registered new tserver with Master: f3ab9d46dcb948a7acd267245637ff1c (127.31.115.1:33365)
23:47:46.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.611567 32613 heartbeater.cc:344] Connected to a master server at 127.31.115.60:39357
23:47:46.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.611709 32613 heartbeater.cc:461] Registering TS with master...
23:47:46.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.612018 32246 ts_manager.cc:194] Registered new tserver with Master: f3ab9d46dcb948a7acd267245637ff1c (127.31.115.1:33365)
23:47:46.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.612198 32613 heartbeater.cc:507] Master 127.31.115.60:39357 requested a full tablet report, sending...
23:47:46.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.613677 32388 ts_manager.cc:194] Registered new tserver with Master: f3ab9d46dcb948a7acd267245637ff1c (127.31.115.1:33365)
23:47:46.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.615265 32388 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.31.115.1:56729
23:47:46.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.761337 32473 catalog_manager.cc:1294] Loaded cluster ID: a91f36f36c2545129b267ec5a44ff613
23:47:46.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.761469 32473 catalog_manager.cc:1587] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: loading cluster ID for follower catalog manager: success
23:47:46.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.764262 32473 catalog_manager.cc:1609] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: acquiring CA information for follower catalog manager: success
23:47:46.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.766145 32473 catalog_manager.cc:1637] T 00000000000000000000000000000000 P 7363ada4436c41df8e0ac7dc0fee56fb: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
23:47:46.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.976670 32623 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:47:46.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.976974 32623 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:47:46.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.977056 32623 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:47:46.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.986125 32623 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:47:46.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:46.986299 32623 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.115.2
23:47:46.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.996641 32623 tablet_server_runner.cc:78] Tablet server non-default flags:
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/wal
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.2:0
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data/info.pb
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.2
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:46.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.115.2
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/logs
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:47:46.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on bdcb31816ec0
23:47:46.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16835
23:47:46.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
23:47:46.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:46.998328 32623 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:47:47.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.000083 32623 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:47:47.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.012072 32629 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
23:47:47.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.012107 32628 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
23:47:47.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.012516 32631 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
23:47:47.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.014974 32623 server_base.cc:1061] running on GCE node
23:47:47.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.015487 32623 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:47:47.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.016336 32623 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:47:47.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.017522 32623 hybrid_clock.cc:648] HybridClock initialized: now 1783468067017464 us; error 64 us; skew 500 ppm
23:47:47.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.017791 32623 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:47:47.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.020000 32623 webserver.cc:534] Webserver started at http://127.31.115.2:33311/ using document root <none> and password file <none>
23:47:47.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.020666 32623 fs_manager.cc:362] Metadata directory not provided
23:47:47.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.020759 32623 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:47:47.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.021116 32623 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:47:47.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.023626 32623 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data/instance:
23:47:47.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "259a211403f3460f817814add094ee81"
23:47:47.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:47 on dist-test-slave-66bt"
23:47:47.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.024319 32623 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/wal/instance:
23:47:47.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "259a211403f3460f817814add094ee81"
23:47:47.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:47 on dist-test-slave-66bt"
23:47:47.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.029860 32623 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.006s	sys 0.001s
23:47:47.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.033209 32637 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:47:47.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.034581 32623 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
23:47:47.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.034721 32623 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data,/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/wal
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "259a211403f3460f817814add094ee81"
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:47 on dist-test-slave-66bt"
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.034931 32623 fs_report.cc:389] FS layout report
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/wal
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/wal
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data/data
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:47:47.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:47:47.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.056398 32623 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:47:47.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.057260 32623 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
23:47:47.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.057498 32623 kserver.cc:163] Server-wide thread pool size limit: 3276
23:47:47.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.058552 32623 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
23:47:47.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.060269 32623 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:47:47.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.060359 32623 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:47:47.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.060431 32623 ts_tablet_manager.cc:616] Registered 0 tablets
23:47:47.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.060477 32623 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
23:47:47.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.097129 32623 rpc_server.cc:307] RPC server started. Bound to: 127.31.115.2:41925
23:47:47.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.097193 32750 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.115.2:41925 every 8 connection(s)
23:47:47.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.099303 32623 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/data/info.pb
23:47:47.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.100834 32204 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu as pid 32623
23:47:47.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.101037 32204 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-1/wal/instance
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.107074 32204 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/wal
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:47:47.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/logs
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data/info.pb
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:47:47.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.3:0
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.115.3
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.3
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:47.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
23:47:47.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.132864 32752 heartbeater.cc:344] Connected to a master server at 127.31.115.62:40183
23:47:47.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.133287 32752 heartbeater.cc:461] Registering TS with master...
23:47:47.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.134373 32751 heartbeater.cc:344] Connected to a master server at 127.31.115.60:39357
23:47:47.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.134517 32751 heartbeater.cc:461] Registering TS with master...
23:47:47.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.135241 32751 heartbeater.cc:507] Master 127.31.115.60:39357 requested a full tablet report, sending...
23:47:47.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.136898 32753 heartbeater.cc:344] Connected to a master server at 127.31.115.61:45795
23:47:47.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.137054 32753 heartbeater.cc:461] Registering TS with master...
23:47:47.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.137337 32388 ts_manager.cc:194] Registered new tserver with Master: 259a211403f3460f817814add094ee81 (127.31.115.2:41925)
23:47:47.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.137559 32753 heartbeater.cc:507] Master 127.31.115.61:45795 requested a full tablet report, sending...
23:47:47.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.138509 32317 ts_manager.cc:194] Registered new tserver with Master: 259a211403f3460f817814add094ee81 (127.31.115.2:41925)
23:47:47.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.138607 32388 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.31.115.2:36555
23:47:47.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.138954 32752 heartbeater.cc:507] Master 127.31.115.62:40183 requested a full tablet report, sending...
23:47:47.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.140447 32246 ts_manager.cc:194] Registered new tserver with Master: 259a211403f3460f817814add094ee81 (127.31.115.2:41925)
23:47:47.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.473932 32760 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:47:47.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.474247 32760 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:47:47.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.474324 32760 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:47:47.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.482684 32760 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:47:47.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.482838 32760 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.115.3
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.492799 32760 tablet_server_runner.cc:78] Tablet server non-default flags:
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.115.20:33645
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/wal
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.115.3:0
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data/info.pb
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.115.3
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.115.62:40183,127.31.115.61:45795,127.31.115.60:39357
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.115.3
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/logs
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:47:47.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on bdcb31816ec0
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16835
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
23:47:47.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.494475 32760 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:47:47.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.496197 32760 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:47:47.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.505836 32766 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
23:47:47.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.505780 32765 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
23:47:47.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:47.506433   300 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
23:47:47.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.507067 32760 server_base.cc:1061] running on GCE node
23:47:47.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.507654 32760 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:47:47.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.508486 32760 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:47:47.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.509675 32760 hybrid_clock.cc:648] HybridClock initialized: now 1783468067509623 us; error 45 us; skew 500 ppm
23:47:47.510 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.509956 32760 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:47:47.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.512178 32760 webserver.cc:534] Webserver started at http://127.31.115.3:46757/ using document root <none> and password file <none>
23:47:47.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.512861 32760 fs_manager.cc:362] Metadata directory not provided
23:47:47.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.512945 32760 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:47:47.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.513289 32760 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:47:47.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.515661 32760 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data/instance:
23:47:47.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "43795bc1f6c84c19b7ef5c894670fe09"
23:47:47.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:47 on dist-test-slave-66bt"
23:47:47.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.516341 32760 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/wal/instance:
23:47:47.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "43795bc1f6c84c19b7ef5c894670fe09"
23:47:47.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:47 on dist-test-slave-66bt"
23:47:47.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.521661 32760 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.006s	sys 0.000s
23:47:47.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.525104   306 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:47:47.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.526465 32760 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.002s
23:47:47.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.526684 32760 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data,/tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/wal
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "43795bc1f6c84c19b7ef5c894670fe09"
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:47:47 on dist-test-slave-66bt"
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.526914 32760 fs_report.cc:389] FS layout report
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/wal
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/wal
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data/data
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:47:47.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:47:47.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:47:47.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:47:47.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.541658 32760 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:47:47.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.542645 32760 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
23:47:47.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.542994 32760 kserver.cc:163] Server-wide thread pool size limit: 3276
23:47:47.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.544044 32760 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
23:47:47.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.545754 32760 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:47:47.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.545881 32760 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:47:47.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.545987 32760 ts_tablet_manager.cc:616] Registered 0 tablets
23:47:47.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.546089 32760 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.001s	sys 0.000s
23:47:47.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.587409 32760 rpc_server.cc:307] RPC server started. Bound to: 127.31.115.3:41457
23:47:47.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.587548   418 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.115.3:41457 every 8 connection(s)
23:47:47.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.589222 32760 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/data/info.pb
23:47:47.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.591921 32204 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu as pid 32760
23:47:47.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.592075 32204 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskMi4vCK/test-tmp/mini-kudu-cluster14393786199156050674/ts-2/wal/instance
23:47:47.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.618542 32613 heartbeater.cc:499] Master 127.31.115.60:39357 was elected leader, sending a full tablet report...
23:47:47.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.620723   419 heartbeater.cc:344] Connected to a master server at 127.31.115.60:39357
23:47:47.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.620746   421 heartbeater.cc:344] Connected to a master server at 127.31.115.61:45795
23:47:47.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.621085   419 heartbeater.cc:461] Registering TS with master...
23:47:47.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.621083   421 heartbeater.cc:461] Registering TS with master...
23:47:47.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.622225   419 heartbeater.cc:507] Master 127.31.115.60:39357 requested a full tablet report, sending...
23:47:47.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.622257   421 heartbeater.cc:507] Master 127.31.115.61:45795 requested a full tablet report, sending...
23:47:47.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.623953   420 heartbeater.cc:344] Connected to a master server at 127.31.115.62:40183
23:47:47.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.623955 32317 ts_manager.cc:194] Registered new tserver with Master: 43795bc1f6c84c19b7ef5c894670fe09 (127.31.115.3:41457)
23:47:47.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.624079   420 heartbeater.cc:461] Registering TS with master...
23:47:47.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.624498 32387 ts_manager.cc:194] Registered new tserver with Master: 43795bc1f6c84c19b7ef5c894670fe09 (127.31.115.3:41457)
23:47:47.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.624563   420 heartbeater.cc:507] Master 127.31.115.62:40183 requested a full tablet report, sending...
23:47:47.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.625505 32246 ts_manager.cc:194] Registered new tserver with Master: 43795bc1f6c84c19b7ef5c894670fe09 (127.31.115.3:41457)
23:47:47.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.625643 32387 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.31.115.3:54445
23:47:47.638 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:47.638176 32204 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
23:47:47.640 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
23:47:47.641 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

23:47:47.651 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.115.62"
      port: 40183
    }
    bound_http_address {
      host: "127.31.115.62"
      port: 33203
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.31.115.61"
      port: 45795
    }
    bound_http_address {
      host: "127.31.115.61"
      port: 33593
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.31.115.60"
      port: 39357
    }
    bound_http_address {
      host: "127.31.115.60"
      port: 42205
    }
  }
}

23:47:47.654 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

23:47:47.656 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.115.1"
      port: 33365
    }
    bound_http_address {
      host: "127.31.115.1"
      port: 42741
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.31.115.2"
      port: 41925
    }
    bound_http_address {
      host: "127.31.115.2"
      port: 33311
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.31.115.3"
      port: 41457
    }
    bound_http_address {
      host: "127.31.115.3"
      port: 46757
    }
  }
}

23:47:47.656 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
23:47:47.820 [DEBUG - main] (CompositeStrategy.java:59) Unable to load modern Subject methods, relying only on legacy methods
java.lang.NoSuchMethodException: javax.security.auth.Subject.current()
	at java.base/java.lang.Class.getDeclaredMethod(Class.java:2677)
	at org.apache.kudu.client.internals.ModernStrategy.<init>(ModernStrategy.java:51)
	at org.apache.kudu.client.internals.CompositeStrategy.<init>(CompositeStrategy.java:52)
	at org.apache.kudu.client.internals.CompositeStrategy.<clinit>(CompositeStrategy.java:41)
	at org.apache.kudu.client.internals.SecurityManagerCompatibility.get(SecurityManagerCompatibility.java:43)
	at org.apache.kudu.client.SecurityContext.setupSubject(SecurityContext.java:155)
	at org.apache.kudu.client.SecurityContext.<init>(SecurityContext.java:138)
	at org.apache.kudu.client.AsyncKuduClient.<init>(AsyncKuduClient.java:431)
	at org.apache.kudu.client.AsyncKuduClient$AsyncKuduClientBuilder.build(AsyncKuduClient.java:3109)
	at org.apache.kudu.test.KuduTestHarness.before(KuduTestHarness.java:161)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:50)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.doOneAttempt(RetryRule.java:225)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:246)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.runner.JUnitCore.runMain(JUnitCore.java:77)
	at org.junit.runner.JUnitCore.main(JUnitCore.java:36)
23:47:47.871 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
23:47:48.025 [DEBUG - main] (Connection.java:748) [peer master-127.31.115.61:45795(127.31.115.61:45795)] connecting to peer
23:47:48.077 [DEBUG - main] (Connection.java:748) [peer master-127.31.115.60:39357(127.31.115.60:39357)] connecting to peer
23:47:48.079 [DEBUG - main] (Connection.java:748) [peer master-127.31.115.62:40183(127.31.115.62:40183)] connecting to peer
23:47:48.094 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.31.115.62:40183(127.31.115.62:40183)] Successfully connected to peer
23:47:48.094 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.31.115.61:45795(127.31.115.61:45795)] Successfully connected to peer
23:47:48.094 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.31.115.60:39357(127.31.115.60:39357)] Successfully connected to peer
23:47:48.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.141964 32751 heartbeater.cc:499] Master 127.31.115.60:39357 was elected leader, sending a full tablet report...
23:47:48.184 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.31.115.61
23:47:48.184 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.31.115.62
23:47:48.186 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.31.115.60
23:47:48.395 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x2a5fb6f2, L:/127.0.0.1:50184 - R:/127.31.115.60:39357] using SASL/PLAIN
23:47:48.395 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xf205ce19, L:/127.0.0.1:50084 - R:/127.31.115.61:45795] using SASL/PLAIN
23:47:48.395 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0x4de56e0f, L:/127.0.0.1:48348 - R:/127.31.115.62:40183] using SASL/PLAIN
23:47:48.433 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
23:47:48.434 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=455), Traces: [0ms] refreshing cache from master, [30ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.115.61:45795, [94ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.115.60:39357, [96ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.115.62:40183, [427ms] Sub RPC ConnectToMaster: received response from server master-127.31.115.61:45795: OK, [427ms] Sub RPC ConnectToMaster: received response from server master-127.31.115.62:40183: OK, [427ms] Sub RPC ConnectToMaster: received response from server master-127.31.115.60:39357: OK, deferred=null) after lookup
23:47:48.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.480515 32387 catalog_manager.cc:2282] Servicing CreateTable request from {username='slave'} at 127.0.0.1:50184:
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1783468063248"
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
23:47:48.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
23:47:48.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
23:47:48.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
23:47:48.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
23:47:48.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:48.482123 32387 catalog_manager.cc:7076] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table org.apache.kudu.client.TestHybridTime-1783468063248 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
23:47:48.522 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 2bb07fee60b54c8a98eee089a4b62f23
23:47:48.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.557837   354 tablet_service.cc:1511] Processing CreateTablet for tablet 0f97af5f8d04431a97e1fc4b42aa3b84 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783468063248 [id=2bb07fee60b54c8a98eee089a4b62f23]), partition=RANGE (key) PARTITION UNBOUNDED
23:47:48.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.559341   354 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 0f97af5f8d04431a97e1fc4b42aa3b84. 1 dirs total, 0 dirs full, 0 dirs failed
23:47:48.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.562214 32548 tablet_service.cc:1511] Processing CreateTablet for tablet 0f97af5f8d04431a97e1fc4b42aa3b84 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783468063248 [id=2bb07fee60b54c8a98eee089a4b62f23]), partition=RANGE (key) PARTITION UNBOUNDED
23:47:48.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.563778 32548 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 0f97af5f8d04431a97e1fc4b42aa3b84. 1 dirs total, 0 dirs full, 0 dirs failed
23:47:48.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.569373 32686 tablet_service.cc:1511] Processing CreateTablet for tablet 0f97af5f8d04431a97e1fc4b42aa3b84 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783468063248 [id=2bb07fee60b54c8a98eee089a4b62f23]), partition=RANGE (key) PARTITION UNBOUNDED
23:47:48.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.570753 32686 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 0f97af5f8d04431a97e1fc4b42aa3b84. 1 dirs total, 0 dirs full, 0 dirs failed
23:47:48.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.593780   450 tablet_bootstrap.cc:492] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c: Bootstrap starting.
23:47:48.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.597702   450 tablet_bootstrap.cc:654] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c: Neither blocks nor log segments found. Creating new log.
23:47:48.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.598428   449 tablet_bootstrap.cc:492] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09: Bootstrap starting.
23:47:48.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.600646   450 log.cc:826] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c: Log is configured to *not* fsync() on all Append() calls
23:47:48.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.602984   449 tablet_bootstrap.cc:654] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09: Neither blocks nor log segments found. Creating new log.
23:47:48.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.604980   449 log.cc:826] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09: Log is configured to *not* fsync() on all Append() calls
23:47:48.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.610743   450 tablet_bootstrap.cc:492] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c: No bootstrap required, opened a new log
23:47:48.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.611263   450 ts_tablet_manager.cc:1403] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c: Time spent bootstrapping tablet: real 0.018s	user 0.007s	sys 0.007s
23:47:48.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.617373   452 tablet_bootstrap.cc:492] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81: Bootstrap starting.
23:47:48.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.621057   452 tablet_bootstrap.cc:654] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81: Neither blocks nor log segments found. Creating new log.
23:47:48.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.622412   450 raft_consensus.cc:359] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.622648   452 log.cc:826] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81: Log is configured to *not* fsync() on all Append() calls
23:47:48.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.623648   450 raft_consensus.cc:385] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:47:48.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.623730   449 tablet_bootstrap.cc:492] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09: No bootstrap required, opened a new log
23:47:48.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.624017   449 ts_tablet_manager.cc:1403] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09: Time spent bootstrapping tablet: real 0.026s	user 0.010s	sys 0.007s
23:47:48.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.626138   450 raft_consensus.cc:740] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: f3ab9d46dcb948a7acd267245637ff1c, State: Initialized, Role: FOLLOWER
23:47:48.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.627074   450 consensus_queue.cc:260] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [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: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.627772   419 heartbeater.cc:499] Master 127.31.115.60:39357 was elected leader, sending a full tablet report...
23:47:48.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.627810   452 tablet_bootstrap.cc:492] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81: No bootstrap required, opened a new log
23:47:48.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.628131   452 ts_tablet_manager.cc:1403] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81: Time spent bootstrapping tablet: real 0.011s	user 0.004s	sys 0.004s
23:47:48.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.633138   450 ts_tablet_manager.cc:1434] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c: Time spent starting tablet: real 0.021s	user 0.011s	sys 0.003s
23:47:48.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.639596   449 raft_consensus.cc:359] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.640622   449 raft_consensus.cc:385] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:47:48.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.640753   449 raft_consensus.cc:740] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 43795bc1f6c84c19b7ef5c894670fe09, State: Initialized, Role: FOLLOWER
23:47:48.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.641794   449 consensus_queue.cc:260] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [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: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.644877   452 raft_consensus.cc:359] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.645246   452 raft_consensus.cc:385] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:47:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.645325   452 raft_consensus.cc:740] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 259a211403f3460f817814add094ee81, State: Initialized, Role: FOLLOWER
23:47:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.645905   452 consensus_queue.cc:260] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [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: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.649490   449 ts_tablet_manager.cc:1434] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09: Time spent starting tablet: real 0.025s	user 0.008s	sys 0.007s
23:47:48.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.651425   452 ts_tablet_manager.cc:1434] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81: Time spent starting tablet: real 0.023s	user 0.011s	sys 0.003s
23:47:48.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:48.835273 32616 tablet.cc:2406] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:47:48.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.855944   456 raft_consensus.cc:493] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
23:47:48.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.856273   456 raft_consensus.cc:515] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.858398   456 leader_election.cc:290] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers f3ab9d46dcb948a7acd267245637ff1c (127.31.115.1:33365), 259a211403f3460f817814add094ee81 (127.31.115.2:41925)
23:47:48.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:48.859557   422 tablet.cc:2406] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:47:48.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.866350 32568 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "0f97af5f8d04431a97e1fc4b42aa3b84" candidate_uuid: "43795bc1f6c84c19b7ef5c894670fe09" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "f3ab9d46dcb948a7acd267245637ff1c" is_pre_election: true
23:47:48.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.866969 32568 raft_consensus.cc:2468] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 43795bc1f6c84c19b7ef5c894670fe09 in term 0.
23:47:48.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.867807 32706 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "0f97af5f8d04431a97e1fc4b42aa3b84" candidate_uuid: "43795bc1f6c84c19b7ef5c894670fe09" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "259a211403f3460f817814add094ee81" is_pre_election: true
23:47:48.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.867863   308 leader_election.cc:304] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [CANDIDATE]: Term 1 pre-election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 43795bc1f6c84c19b7ef5c894670fe09, f3ab9d46dcb948a7acd267245637ff1c; no voters: 
23:47:48.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.868263 32706 raft_consensus.cc:2468] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 43795bc1f6c84c19b7ef5c894670fe09 in term 0.
23:47:48.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.868322   456 raft_consensus.cc:2804] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Leader pre-election won for term 1
23:47:48.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.868470   456 raft_consensus.cc:493] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
23:47:48.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.868566   456 raft_consensus.cc:3060] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 0 FOLLOWER]: Advancing to term 1
23:47:48.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:48.869383 32757 tablet.cc:2406] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:47:48.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.873171   456 raft_consensus.cc:515] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.873835   456 leader_election.cc:290] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [CANDIDATE]: Term 1 election: Requested vote from peers f3ab9d46dcb948a7acd267245637ff1c (127.31.115.1:33365), 259a211403f3460f817814add094ee81 (127.31.115.2:41925)
23:47:48.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.875176 32568 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "0f97af5f8d04431a97e1fc4b42aa3b84" candidate_uuid: "43795bc1f6c84c19b7ef5c894670fe09" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "f3ab9d46dcb948a7acd267245637ff1c"
23:47:48.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.875252 32706 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "0f97af5f8d04431a97e1fc4b42aa3b84" candidate_uuid: "43795bc1f6c84c19b7ef5c894670fe09" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "259a211403f3460f817814add094ee81"
23:47:48.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.875383 32568 raft_consensus.cc:3060] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [term 0 FOLLOWER]: Advancing to term 1
23:47:48.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.875451 32706 raft_consensus.cc:3060] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [term 0 FOLLOWER]: Advancing to term 1
23:47:48.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.877942 32568 raft_consensus.cc:2468] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 43795bc1f6c84c19b7ef5c894670fe09 in term 1.
23:47:48.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.878350   308 leader_election.cc:304] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 43795bc1f6c84c19b7ef5c894670fe09, f3ab9d46dcb948a7acd267245637ff1c; no voters: 
23:47:48.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.878643   456 raft_consensus.cc:2804] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 1 FOLLOWER]: Leader election won for term 1
23:47:48.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.878690 32706 raft_consensus.cc:2468] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 43795bc1f6c84c19b7ef5c894670fe09 in term 1.
23:47:48.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.879190   456 raft_consensus.cc:697] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [term 1 LEADER]: Becoming Leader. State: Replica: 43795bc1f6c84c19b7ef5c894670fe09, State: Running, Role: LEADER
23:47:48.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.879802   456 consensus_queue.cc:237] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [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: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } }
23:47:48.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:48.887660 32388 catalog_manager.cc:5696] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 reported cstate change: term changed from 0 to 1, leader changed from <none> to 43795bc1f6c84c19b7ef5c894670fe09 (127.31.115.3). New cstate: current_term: 1 leader_uuid: "43795bc1f6c84c19b7ef5c894670fe09" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "43795bc1f6c84c19b7ef5c894670fe09" member_type: VOTER last_known_addr { host: "127.31.115.3" port: 41457 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 } health_report { overall_health: UNKNOWN } } }
23:47:49.152 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 0f97af5f8d04431a97e1fc4b42aa3b84 for table 'org.apache.kudu.client.TestHybridTime-1783468063248' with partition [<start>, <end>)
23:47:49.157 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=0f97af5f8d04431a97e1fc4b42aa3b84@[259a211403f3460f817814add094ee81(127.31.115.2:41925),43795bc1f6c84c19b7ef5c894670fe09(127.31.115.3:41457)[L],f3ab9d46dcb948a7acd267245637ff1c(127.31.115.1:33365)]}]
23:47:49.158 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=15), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.31.115.60:39357, [7ms] Sub RPC GetTableLocations: received response from server master-127.31.115.60:39357: OK, deferred=null) after lookup
23:47:49.169 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 43795bc1f6c84c19b7ef5c894670fe09(127.31.115.3:41457)] connecting to peer
23:47:49.171 [DEBUG - kudu-nio-3] (Connection.java:757) [peer 43795bc1f6c84c19b7ef5c894670fe09(127.31.115.3:41457)] Successfully connected to peer
23:47:49.230 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x0dd14fc4, L:/127.0.0.1:59592 - R:/127.31.115.3:41457] using TOKEN/null
23:47:49.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.242475 32706 raft_consensus.cc:1275] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 259a211403f3460f817814add094ee81 [term 1 FOLLOWER]: Refusing update from remote peer 43795bc1f6c84c19b7ef5c894670fe09: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:47:49.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.242745 32568 raft_consensus.cc:1275] T 0f97af5f8d04431a97e1fc4b42aa3b84 P f3ab9d46dcb948a7acd267245637ff1c [term 1 FOLLOWER]: Refusing update from remote peer 43795bc1f6c84c19b7ef5c894670fe09: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:47:49.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.243656   456 consensus_queue.cc:1048] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [LEADER]: Connected to new peer: Peer: permanent_uuid: "259a211403f3460f817814add094ee81" member_type: VOTER last_known_addr { host: "127.31.115.2" port: 41925 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
23:47:49.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.243969   460 consensus_queue.cc:1048] T 0f97af5f8d04431a97e1fc4b42aa3b84 P 43795bc1f6c84c19b7ef5c894670fe09 [LEADER]: Connected to new peer: Peer: permanent_uuid: "f3ab9d46dcb948a7acd267245637ff1c" member_type: VOTER last_known_addr { host: "127.31.115.1" port: 33365 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
23:47:49.321 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:47:49.335 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:47:49.342 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:47:49.346 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1627387998(state=PENDING, result=null, callback=<none>, errback=<none>)}
23:47:49.360 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:47:49.361 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@891614554(state=PENDING, result=null, callback=<none>, errback=<none>)}
23:47:49.370 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:47:49.370 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@735558525(state=PENDING, result=null, callback=<none>, errback=<none>)}
23:47:49.378 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:47:49.437 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.446 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7305085232085356545) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.453 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7305085232085356549) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.460 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7305085232085356553) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.466 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7305085232085356557) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.473 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7305085232085356563) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.479 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7305085232085356567) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.486 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7305085232085356568) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468063248, tablet=0f97af5f8d04431a97e1fc4b42aa3b84, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:47:49.490 [DEBUG - main] (Connection.java:575) [peer master-127.31.115.61:45795(127.31.115.61:45795)] disconnecting while in state READY
23:47:49.491 [DEBUG - main] (Connection.java:575) [peer 43795bc1f6c84c19b7ef5c894670fe09(127.31.115.3:41457)] disconnecting while in state READY
23:47:49.491 [DEBUG - main] (Connection.java:575) [peer master-127.31.115.62:40183(127.31.115.62:40183)] disconnecting while in state READY
23:47:49.492 [DEBUG - main] (Connection.java:575) [peer master-127.31.115.60:39357(127.31.115.60:39357)] disconnecting while in state READY
23:47:49.506 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.31.115.60:39357(127.31.115.60:39357)] handling channelInactive
23:47:49.506 [DEBUG - kudu-nio-3] (Connection.java:239) [peer 43795bc1f6c84c19b7ef5c894670fe09(127.31.115.3:41457)] handling channelInactive
23:47:49.506 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.31.115.62:40183(127.31.115.62:40183)] handling channelInactive
23:47:49.506 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.31.115.60:39357(127.31.115.60:39357)] cleaning up while in state READY due to: connection closed
23:47:49.507 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2704) Releasing all remaining resources
23:47:49.507 [DEBUG - kudu-nio-3] (Connection.java:709) [peer 43795bc1f6c84c19b7ef5c894670fe09(127.31.115.3:41457)] cleaning up while in state READY due to: connection closed
23:47:49.508 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.31.115.62:40183(127.31.115.62:40183)] cleaning up while in state READY due to: connection closed
23:47:49.518 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.31.115.61:45795(127.31.115.61:45795)] handling channelInactive
23:47:49.518 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.31.115.61:45795(127.31.115.61:45795)] cleaning up while in state READY due to: connection closed
23:47:49.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.519537 32204 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu pid 32484
23:47:49.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.554929 32608 generic_service.cc:196] Checking for leaks (request via RPC)
23:47:49.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.693861 32204 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu with pid 32484
23:47:49.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.718822 32204 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu pid 32623
23:47:49.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.726469 32746 generic_service.cc:196] Checking for leaks (request via RPC)
23:47:49.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.822086 32204 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu with pid 32623
23:47:49.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.840104 32204 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu pid 32760
23:47:49.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.848062   414 generic_service.cc:196] Checking for leaks (request via RPC)
23:47:49.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:49.985023 32204 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu with pid 32760
23:47:50.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.002247 32204 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu pid 32215
23:47:50.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.003373 32276 generic_service.cc:196] Checking for leaks (request via RPC)
23:47:50.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.108444 32204 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu with pid 32215
23:47:50.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.128994 32204 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu pid 32284
23:47:50.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.130152 32347 generic_service.cc:196] Checking for leaks (request via RPC)
23:47:50.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:50.213793 32372 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.31.115.62:40183: connect: Connection refused (error 111)
23:47:50.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:47:50.215559 32372 consensus_peers.cc:597] T 00000000000000000000000000000000 P 5ba241becf114f6cbf5388a9c0ea0c19 -> Peer de428f0f07664efeb9fe7fe4013bac89 (127.31.115.62:40183): Couldn't send request to peer de428f0f07664efeb9fe7fe4013bac89. Status: Network error: Client connection negotiation failed: client connection to 127.31.115.62:40183: connect: Connection refused (error 111). This is attempt 1: this message will repeat every 5th retry.
23:47:50.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.234467 32204 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu with pid 32284
23:47:50.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.254339 32204 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu pid 32354
23:47:50.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.255425 32418 generic_service.cc:196] Checking for leaks (request via RPC)
23:47:50.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:47:50.363232 32204 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskMi4vCK/build/asan/bin/kudu with pid 32354
23:47:50.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:47:50Z chronyd exiting

Time: 7.175

OK (1 test)

