JUnit version 4.13.2
.16:15:30.756 [DEBUG - main] (TestNegotiationTLSv13.java:108) enabled TLS protocols: TLSv1.3 TLSv1.2 TLSv1.1 TLSv1
16:15:30.759 [DEBUG - main] (TestNegotiationTLSv13.java:110) supported TLS protocols: TLSv1.3 TLSv1.2 SSLv3 TLSv1.1 SSLv2Hello TLSv1
16:15:30.767 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskBrq1Pz/test-tmp
16:15:30.769 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
16:15:30.772 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskBrq1Pz/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
16:15:31.048 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 0
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693"
  extra_master_flags: "--time_source=system_unsync"
  extra_master_flags: "--rpc_tls_min_protocol=TLSv1.3"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

16:15:31.165 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
16:15:31.166 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

16:15:31.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.170214 32042 mini_oidc.cc:152] Starting JWKS server
16:15:31.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.174075 32042 webserver.cc:460] Webserver started at http://127.0.0.1:38063/ using document root <none> and password file <none>
16:15:31.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.174705 32042 mini_oidc.cc:189] Starting OIDC Discovery server
16:15:31.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.176570 32042 webserver.cc:460] Webserver started at http://127.0.0.1:33977/ using document root <none> and password file <none>
16:15:31.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T16:15:31Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
16:15:31.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T16:15:31Z Disabled control of system clock
16:15:31.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.229137 32042 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:31.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:31.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/wal
16:15:31.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/data
16:15:31.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:15:31.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:15:31.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:15:31.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:31.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:31.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:31.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/logs
16:15:31.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/data/info.pb
16:15:31.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:31.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:15:31.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:15:31.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:15:31.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:15:31.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:31.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:31.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:31.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
16:15:31.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:15:31.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:15:31.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:15:31.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.74.190:42633
16:15:31.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.74.190
16:15:31.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.74.148:36599
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.74.190:42633
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:15:31.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38063/jwks.json
16:15:31.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
16:15:31.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
16:15:31.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.531985 32072 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:15:31.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.532632 32072 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:15:31.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.533237 32072 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:15:31.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.563648 32072 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
16:15:31.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.563922 32072 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:15:31.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.564111 32072 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
16:15:31.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.564370 32072 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
16:15:31.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.564647 32072 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
16:15:31.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.564796 32072 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:38063/jwks.json
16:15:31.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.605654 32072 master_runner.cc:386] Master server non-default flags:
16:15:31.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:31.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.74.148:36599
16:15:31.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/data
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/wal
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.74.190:42633
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.74.190:42633
16:15:31.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38063/jwks.json
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/data/info.pb
16:15:31.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.74.190
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/logs
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
16:15:31.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:15:31.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:15:31.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
16:15:31.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 15:14:16 UTC on ccycloud.quasar-uwbvwl.root.comops.site
16:15:31.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4580677
16:15:31.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
16:15:31.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.606922 32072 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:15:31.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.608719 32072 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:15:31.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.627579 32086 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
16:15:32.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.627631 32090 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
16:15:32.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:31.628072 32084 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
16:15:32.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:31.628490 32072 server_base.cc:1034] running on GCE node
16:15:32.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.822795 32072 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
16:15:32.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:32.823064 32072 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
16:15:32.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.823292 32072 hybrid_clock.cc:648] HybridClock initialized: now 1786464932823250 us; error 0 us; skew 500 ppm
16:15:32.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.824259 32072 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
16:15:32.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.836067 32072 webserver.cc:460] Webserver started at http://127.31.74.190:46473/ using document root <none> and password file <none>
16:15:32.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.837177 32072 fs_manager.cc:359] Metadata directory not provided
16:15:32.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.837416 32072 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:15:32.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.837985 32072 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:15:32.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.843031 32072 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/data/instance:
16:15:32.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5d1f159ed64d46af92f21c8a13e68715"
16:15:32.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:32 on dist-test-slave-rwrg"
16:15:32.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.844276 32072 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/wal/instance:
16:15:32.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5d1f159ed64d46af92f21c8a13e68715"
16:15:32.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:32 on dist-test-slave-rwrg"
16:15:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.851680 32072 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.000s
16:15:32.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.857213 32098 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:15:32.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.858239 32072 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.004s	sys 0.000s
16:15:32.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.858623 32072 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/data,/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/wal
16:15:32.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5d1f159ed64d46af92f21c8a13e68715"
16:15:32.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:32 on dist-test-slave-rwrg"
16:15:32.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:32.859015 32072 fs_report.cc:383] FS layout report
16:15:32.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/wal
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/wal
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster6134173935186514693/master-0/data/data
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:15:32.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:15:32.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:15:32.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:15:32.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:15:32.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:15:32.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:15:32.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:15:32.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Invalid argument: invalid minimum TLS protocol version (--rpc_tls_min_protocol): this platform does not support TLSv1.3
16:15:33.956 [DEBUG - main] (MiniKuduCluster.java:195) Response: error {
  code: RUNTIME_ERROR
  message: "failed to start masters: Unable to start Master at index 0: /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu: process exited with non-zero status 1"
}

16:15:35.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T16:15:35Z chronyd exiting
16:15:36.153 [INFO - main] (TestNegotiationTLSv13.java:145) server side doesn't support TLSv1.3
java.io.IOException: failed to start masters: Unable to start Master at index 0: /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu: process exited with non-zero status 1
	at org.apache.kudu.test.cluster.MiniKuduCluster.sendRequestToCluster(MiniKuduCluster.java:199) ~[kudu-test-utils-1.18.0-SNAPSHOT-unshaded.jar:1.18.0-SNAPSHOT]
	at org.apache.kudu.test.cluster.MiniKuduCluster.start(MiniKuduCluster.java:266) ~[kudu-test-utils-1.18.0-SNAPSHOT-unshaded.jar:1.18.0-SNAPSHOT]
	at org.apache.kudu.test.cluster.MiniKuduCluster.access$300(MiniKuduCluster.java:85) ~[kudu-test-utils-1.18.0-SNAPSHOT-unshaded.jar:1.18.0-SNAPSHOT]
	at org.apache.kudu.test.cluster.MiniKuduCluster$MiniKuduClusterBuilder.build(MiniKuduCluster.java:883) ~[kudu-test-utils-1.18.0-SNAPSHOT-unshaded.jar:1.18.0-SNAPSHOT]
	at org.apache.kudu.client.TestNegotiationTLSv13.isTLSv13SupportedByServerSide(TestNegotiationTLSv13.java:133) [test/:?]
	at org.apache.kudu.client.TestNegotiationTLSv13.<init>(TestNegotiationTLSv13.java:157) [test/:?]
	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[?:1.8.0_362]
	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62) [?:1.8.0_362]
	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) [?:1.8.0_362]
	at java.lang.reflect.Constructor.newInstance(Constructor.java:423) [?:1.8.0_362]
	at org.junit.runners.BlockJUnit4ClassRunner.createTest(BlockJUnit4ClassRunner.java:250) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.BlockJUnit4ClassRunner.createTest(BlockJUnit4ClassRunner.java:260) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.BlockJUnit4ClassRunner$2.runReflectiveCall(BlockJUnit4ClassRunner.java:309) [junit-4.13.2.jar:4.13.2]
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.BlockJUnit4ClassRunner.methodBlock(BlockJUnit4ClassRunner.java:306) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.Suite.runChild(Suite.java:128) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.Suite.runChild(Suite.java:27) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) [junit-4.13.2.jar:4.13.2]
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413) [junit-4.13.2.jar:4.13.2]
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137) [junit-4.13.2.jar:4.13.2]
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115) [junit-4.13.2.jar:4.13.2]
	at org.junit.runner.JUnitCore.runMain(JUnitCore.java:77) [junit-4.13.2.jar:4.13.2]
	at org.junit.runner.JUnitCore.main(JUnitCore.java:36) [junit-4.13.2.jar:4.13.2]
16:15:36.162 [INFO - main] (RandomUtils.java:49) Using random seed: 1786464936162
16:15:36.169 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
16:15:36.213 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
16:15:36.214 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskBrq1Pz/test-tmp
16:15:36.214 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
16:15:36.215 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskBrq1Pz/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
16:15:36.217 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024"
  extra_master_flags: "--rpc-encryption=required"
  extra_master_flags: "--rpc_encrypt_loopback_connections"
  extra_master_flags: "--rpc-trace-negotiation"
  extra_tserver_flags: "--rpc-encryption=required"
  extra_tserver_flags: "--rpc_encrypt_loopback_connections"
  extra_tserver_flags: "--rpc-trace-negotiation"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

16:15:36.544 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
16:15:36.545 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

16:15:36.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:36.548159 32101 mini_oidc.cc:152] Starting JWKS server
16:15:36.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:36.551610 32101 webserver.cc:460] Webserver started at http://127.0.0.1:33865/ using document root <none> and password file <none>
16:15:36.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:36.552021 32101 mini_oidc.cc:189] Starting OIDC Discovery server
16:15:36.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:36.553691 32101 webserver.cc:460] Webserver started at http://127.0.0.1:36061/ using document root <none> and password file <none>
16:15:36.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
16:15:36.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/principal' for realm 'KRBTEST.COM',
16:15:36.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
16:15:36.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:36 dist-test-slave-rwrg krb5kdc[32122](info): setting up network...
16:15:36.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
16:15:36.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:36 dist-test-slave-rwrg krb5kdc[32122](info): set up 2 sockets
16:15:36.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:36 dist-test-slave-rwrg krb5kdc[32122](info): commencing operation
16:15:36.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
16:15:37.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:37.976675 32101 mini_kdc.cc:121] Time spent starting KDC: real 1.397s	user 0.003s	sys 0.019s
16:15:37.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
16:15:38.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:15:38.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
16:15:38.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
16:15:38.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:15:38.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
16:15:38.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
16:15:38.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:15:38.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
16:15:38.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:15:38.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal test-user with kvno 1, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/test-user.keytab.
16:15:38.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal test-user with kvno 1, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/test-user.keytab.
16:15:38.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:38 dist-test-slave-rwrg krb5kdc[32122](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464938, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:15:38.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
16:15:38.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T16:15:38Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
16:15:38.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T16:15:38Z Disabled control of system clock
16:15:38.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.89.126@KRBTEST.COM; defaulting to no policy
16:15:38.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:38.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.89.126@KRBTEST.COM" created.
16:15:38.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:38.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.126 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.126.keytab.
16:15:38.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.126 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.126.keytab.
16:15:38.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:38.183038 32101 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:38.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:38.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/wal
16:15:38.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data
16:15:38.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:15:38.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:15:38.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:15:38.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:38.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/logs
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data/info.pb
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:15:38.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.126:36559
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.126
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.126.keytab
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.126
16:15:38.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.89.126:36559
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33865/jwks.json
16:15:38.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:15:38.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:15:38.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:15:38.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.483891 32157 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:15:38.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.484627 32157 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:15:38.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.485388 32157 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:15:38.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.516422 32157 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
16:15:38.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.516899 32157 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:15:38.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.517307 32157 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:15:38.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.517666 32157 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
16:15:38.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.518013 32157 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
16:15:38.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.518347 32157 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
16:15:38.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.518620 32157 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:33865/jwks.json
16:15:38.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:38.555344 32157 master_runner.cc:386] Master server non-default flags:
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/wal
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:15:38.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.89.126:36559
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.126.keytab
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.126
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.126:36559
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:15:38.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:15:38.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:15:38.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33865/jwks.json
16:15:38.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data/info.pb
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.126
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:38.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/logs
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
16:15:38.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 15:14:16 UTC on ccycloud.quasar-uwbvwl.root.comops.site
16:15:38.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4580677
16:15:38.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
16:15:38.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:38.556836 32157 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:15:38.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:38.558322 32157 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:15:38.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.576287 32171 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
16:15:39.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.576359 32168 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
16:15:39.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:38.577862 32157 server_base.cc:1034] running on GCE node
16:15:39.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:38.576370 32169 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
16:15:39.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.756974 32157 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:15:39.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.759631 32157 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:15:39.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.760993 32157 hybrid_clock.cc:648] HybridClock initialized: now 1786464939760951 us; error 65 us; skew 500 ppm
16:15:39.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.761884 32157 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
16:15:39.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:39 dist-test-slave-rwrg krb5kdc[32122](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464939, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.89.126@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:15:39.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.772332 32157 init.cc:377] Logged in from keytab as kudu/127.31.89.126@KRBTEST.COM (short username kudu)
16:15:39.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.777626 32157 webserver.cc:460] Webserver started at http://127.31.89.126:42609/ using document root <none> and password file <none>
16:15:39.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.778633 32157 fs_manager.cc:359] Metadata directory not provided
16:15:39.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.778890 32157 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:15:39.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.779438 32157 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:15:39.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.784619 32157 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data/instance:
16:15:39.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "081ba33ad5a94ae4aa9f15506c0c056a"
16:15:39.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:39 on dist-test-slave-rwrg"
16:15:39.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.785903 32157 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/wal/instance:
16:15:39.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "081ba33ad5a94ae4aa9f15506c0c056a"
16:15:39.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:39 on dist-test-slave-rwrg"
16:15:39.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.793599 32157 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.007s	sys 0.001s
16:15:39.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.799332 32188 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:15:39.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.800725 32157 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.000s
16:15:39.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.801064 32157 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data,/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/wal
16:15:39.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "081ba33ad5a94ae4aa9f15506c0c056a"
16:15:39.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:39 on dist-test-slave-rwrg"
16:15:39.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.801393 32157 fs_report.cc:383] FS layout report
16:15:39.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/wal
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/wal
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data/data
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:15:39.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:15:39.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:15:39.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.842895 32157 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:15:39.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.848670 32157 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
16:15:39.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.849092 32157 kserver.cc:163] Server-wide thread pool size limit: 3276
16:15:39.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.920473 32157 rpc_server.cc:307] RPC server started. Bound to: 127.31.89.126:36559
16:15:39.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.920707 32276 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.31.89.126:36559 every 8 connection(s)
16:15:39.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.923743 32157 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/data/info.pb
16:15:39.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.927093 32101 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu as pid 32157
16:15:39.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.927688 32101 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/master-0/wal/instance
16:15:39.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.930260 32277 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
16:15:39.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.952442 32277 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a: Bootstrap starting.
16:15:39.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.959633 32277 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a: Neither blocks nor log segments found. Creating new log.
16:15:39.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.961820 32277 log.cc:824] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a: Log is configured to *not* fsync() on all Append() calls
16:15:39.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.968075 32277 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a: No bootstrap required, opened a new log
16:15:39.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:39 dist-test-slave-rwrg krb5kdc[32122](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464938, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.31.89.126@KRBTEST.COM
16:15:39.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.987419 32279 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.934543 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:35540 (local address 127.31.89.126:36559)
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.937468 (+  2925us) server_negotiation.cc:206] Beginning negotiation
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.937520 (+    52us) server_negotiation.cc:399] Waiting for connection header
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.938932 (+  1412us) server_negotiation.cc:407] Connection header received
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.951720 (+ 12788us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.951771 (+    51us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.952252 (+   481us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.952805 (+   553us) server_negotiation.cc:226] Negotiated authn=SASL
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.958475 (+  5670us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.960471 (+  1996us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.964043 (+  3572us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:39.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.965623 (+  1580us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:39.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.965903 (+   280us) server_negotiation.cc:662] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
16:15:39.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.974999 (+  9096us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:15:39.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.975023 (+    24us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:15:39.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.975052 (+    29us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.975165 (+   113us) server_negotiation.cc:922] calling sasl_server_start()
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.979367 (+  4202us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.981199 (+  1832us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.981224 (+    25us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.981241 (+    17us) server_negotiation.cc:967] calling sasl_server_step()
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.981463 (+   222us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.982375 (+   912us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.982416 (+    41us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.982443 (+    27us) server_negotiation.cc:967] calling sasl_server_step()
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.983182 (+   739us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.983546 (+   364us) server_negotiation.cc:1035] Waiting for connection context
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.984899 (+  1353us) server_negotiation.cc:299] Negotiation successful
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:39.985265 (+   366us) negotiation.cc:327] Negotiation complete: OK
16:15:39.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":2348,"thread_start_us":1900,"threads_started":1}
16:15:39.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.993479 32277 raft_consensus.cc:348] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" member_type: VOTER last_known_addr { host: "127.31.89.126" port: 36559 } }
16:15:39.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.994124 32277 raft_consensus.cc:374] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
16:15:39.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.994311 32277 raft_consensus.cc:729] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 081ba33ad5a94ae4aa9f15506c0c056a, State: Initialized, Role: FOLLOWER
16:15:39.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.995038 32277 consensus_queue.cc:260] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [NON_LEADER]: Queue going to NON_LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 0, Majority size: -1, State: 0, Mode: NON_LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" member_type: VOTER last_known_addr { host: "127.31.89.126" port: 36559 } }
16:15:39.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.995702 32277 raft_consensus.cc:388] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
16:15:39.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.996093 32277 raft_consensus.cc:482] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
16:15:39.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:39.996870 32277 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 0 FOLLOWER]: Advancing to term 1
16:15:40.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.002377 32277 raft_consensus.cc:504] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" member_type: VOTER last_known_addr { host: "127.31.89.126" port: 36559 } }
16:15:40.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.003062 32277 leader_election.cc:302] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 1 responses out of 1 voters: 1 yes votes; 0 no votes. yes voters: 081ba33ad5a94ae4aa9f15506c0c056a; no voters: 
16:15:40.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.004917 32277 leader_election.cc:288] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [CANDIDATE]: Term 1 election: Requested vote from peers 
16:15:40.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.005285 32286 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 1 FOLLOWER]: Leader election won for term 1
16:15:40.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.008441 32286 raft_consensus.cc:686] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [term 1 LEADER]: Becoming Leader. State: Replica: 081ba33ad5a94ae4aa9f15506c0c056a, State: Running, Role: LEADER
16:15:40.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.009347 32286 consensus_queue.cc:237] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [LEADER]: Queue going to LEADER mode. State: All replicated index: 0, Majority replicated index: 0, Committed index: 0, Last appended: 0.0, Last appended by leader: 0, Current term: 1, Majority size: 1, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" member_type: VOTER last_known_addr { host: "127.31.89.126" port: 36559 } }
16:15:40.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.009987 32277 sys_catalog.cc:564] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [sys.catalog]: configured and running, proceeding with master startup.
16:15:40.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.020581 32289 sys_catalog.cc:455] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [sys.catalog]: SysCatalogTable state changed. Reason: New leader 081ba33ad5a94ae4aa9f15506c0c056a. Latest consensus state: current_term: 1 leader_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" member_type: VOTER last_known_addr { host: "127.31.89.126" port: 36559 } } }
16:15:40.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.020965 32288 sys_catalog.cc:455] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "081ba33ad5a94ae4aa9f15506c0c056a" member_type: VOTER last_known_addr { host: "127.31.89.126" port: 36559 } } }
16:15:40.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.021515 32289 sys_catalog.cc:458] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [sys.catalog]: This master's current role is: LEADER
16:15:40.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.021685 32288 sys_catalog.cc:458] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a [sys.catalog]: This master's current role is: LEADER
16:15:40.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.028889 32301 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
16:15:40.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.040966 32301 catalog_manager.cc:1484] Initializing Kudu cluster ID...
16:15:40.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.057904 32301 catalog_manager.cc:1347] Generated new cluster ID: e7d59c8f21904443ae7c61498cce6aee
16:15:40.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.058223 32301 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
16:15:40.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.079914 32301 catalog_manager.cc:1370] Generated new certificate authority record
16:15:40.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.081867 32301 catalog_manager.cc:1504] Loading token signing keys...
16:15:40.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.098543 32301 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 081ba33ad5a94ae4aa9f15506c0c056a: Generated new TSK 0
16:15:40.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.099552 32301 catalog_manager.cc:1514] Initializing in-progress tserver states...
16:15:40.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.89.65@KRBTEST.COM; defaulting to no policy
16:15:40.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:40.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.89.65@KRBTEST.COM" created.
16:15:40.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:40.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.65 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.65.keytab.
16:15:40.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.65 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.65.keytab.
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.157914 32101 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/wal
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/logs
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data/info.pb
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:15:40.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.65:0
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.89.65
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.65
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.89.126:36559
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.65.keytab
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.65
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:15:40.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:15:40.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.473358 32323 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:15:40.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.473839 32323 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:15:40.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.474418 32323 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:15:40.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.504760 32323 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:15:40.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.505089 32323 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:15:40.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.505970 32323 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.89.65
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.541865 32323 tablet_server_runner.cc:78] Tablet server non-default flags:
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/wal
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:15:40.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.65.keytab
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.65
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.65:0
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data/info.pb
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.65
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.89.126:36559
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:15:40.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.89.65
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/logs
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 15:14:16 UTC on ccycloud.quasar-uwbvwl.root.comops.site
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4580677
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
16:15:40.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.542932 32323 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:15:40.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.543915 32323 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:15:40.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.567374 32338 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
16:15:41.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.567404 32334 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
16:15:41.763 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:40.570118 32323 server_base.cc:1034] running on GCE node
16:15:41.763 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:40.569839 32344 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
16:15:41.767 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.767088 32323 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:15:41.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.769938 32323 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:15:41.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.771378 32323 hybrid_clock.cc:648] HybridClock initialized: now 1786464941771349 us; error 50 us; skew 500 ppm
16:15:41.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.772140 32323 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
16:15:41.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:41 dist-test-slave-rwrg krb5kdc[32122](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464941, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.89.65@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:15:41.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.778353 32323 init.cc:377] Logged in from keytab as kudu/127.31.89.65@KRBTEST.COM (short username kudu)
16:15:41.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.784740 32323 webserver.cc:460] Webserver started at http://127.31.89.65:41117/ using document root <none> and password file <none>
16:15:41.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.785744 32323 fs_manager.cc:359] Metadata directory not provided
16:15:41.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.785990 32323 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:15:41.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.786568 32323 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:15:41.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.791805 32323 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data/instance:
16:15:41.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "20738c51f5c64978be629bb9a56d5d1e"
16:15:41.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:41 on dist-test-slave-rwrg"
16:15:41.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.793138 32323 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/wal/instance:
16:15:41.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "20738c51f5c64978be629bb9a56d5d1e"
16:15:41.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:41 on dist-test-slave-rwrg"
16:15:41.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.801422 32323 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.007s	sys 0.001s
16:15:41.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.807790 32363 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:15:41.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.808981 32323 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.006s	sys 0.001s
16:15:41.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.809291 32323 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data,/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/wal
16:15:41.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "20738c51f5c64978be629bb9a56d5d1e"
16:15:41.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:41 on dist-test-slave-rwrg"
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.809708 32323 fs_report.cc:383] FS layout report
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/wal
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/wal
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data/data
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:15:41.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:15:41.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:15:41.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:15:41.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:15:41.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:15:41.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.851258 32323 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:15:41.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.857264 32323 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
16:15:41.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.857823 32323 kserver.cc:163] Server-wide thread pool size limit: 3276
16:15:41.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.860459 32323 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
16:15:41.864 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.864428 32323 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
16:15:41.864 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.864712 32323 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.001s	sys 0.000s
16:15:41.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.865080 32323 ts_tablet_manager.cc:610] Registered 0 tablets
16:15:41.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:41.865298 32323 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
16:15:42.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.025053 32323 rpc_server.cc:307] RPC server started. Bound to: 127.31.89.65:40641
16:15:42.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.025169 32599 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.31.89.65:40641 every 8 connection(s)
16:15:42.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.027755 32323 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/data/info.pb
16:15:42.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.029362 32101 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu as pid 32323
16:15:42.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.029837 32101 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-0/wal/instance
16:15:42.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.89.66@KRBTEST.COM; defaulting to no policy
16:15:42.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:42.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.89.66@KRBTEST.COM" created.
16:15:42.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:42 dist-test-slave-rwrg krb5kdc[32122](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464941, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.89.65@KRBTEST.COM for kudu/127.31.89.126@KRBTEST.COM
16:15:42.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.071635 32604 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.033942 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.31.89.126:36559 (local address 127.31.89.65:47075)
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.036783 (+  2841us) negotiation.cc:107] Waiting for socket to connect
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.036854 (+    71us) client_negotiation.cc:174] Beginning negotiation
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.041503 (+  4649us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.042680 (+  1177us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.042730 (+    50us) client_negotiation.cc:394] Received NEGOTIATE response from server
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.043711 (+   981us) client_negotiation.cc:189] Negotiated authn=SASL
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.044710 (+   999us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.044759 (+    49us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.046338 (+  1579us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.046359 (+    21us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.047979 (+  1620us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.048019 (+    40us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.049475 (+  1456us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.049513 (+    38us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.050006 (+   493us) client_negotiation.cc:553] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
16:15:42.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.051026 (+  1020us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.051095 (+    69us) client_negotiation.cc:656] calling sasl_client_start()
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.057351 (+  6256us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.064338 (+  6987us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.064362 (+    24us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.064387 (+    25us) client_negotiation.cc:760] calling sasl_client_step()
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.065267 (+   880us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.066261 (+   994us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.066279 (+    18us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.066293 (+    14us) client_negotiation.cc:760] calling sasl_client_step()
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.066563 (+   270us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.068074 (+  1511us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.068104 (+    30us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.068591 (+   487us) client_negotiation.cc:769] Sending connection context
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.069025 (+   434us) client_negotiation.cc:240] Negotiation successful
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.069411 (+   386us) negotiation.cc:327] Negotiation complete: OK
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2313,"thread_start_us":1525,"threads_started":1}
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.071786 32603 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.033545 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.31.89.65:47075 (local address 127.31.89.126:36559)
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.034835 (+  1290us) server_negotiation.cc:206] Beginning negotiation
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.034849 (+    14us) server_negotiation.cc:399] Waiting for connection header
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.037014 (+  2165us) server_negotiation.cc:407] Connection header received
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.041930 (+  4916us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.041967 (+    37us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.042144 (+   177us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.042345 (+   201us) server_negotiation.cc:226] Negotiated authn=SASL
16:15:42.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.045042 (+  2697us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:42.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.046061 (+  1019us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:42.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.048269 (+  2208us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:42.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.049103 (+   834us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:42.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.049422 (+   319us) server_negotiation.cc:662] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.058316 (+  8894us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.058347 (+    31us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.058376 (+    29us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.058504 (+   128us) server_negotiation.cc:922] calling sasl_server_start()
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.063990 (+  5486us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.065642 (+  1652us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.065693 (+    51us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.065724 (+    31us) server_negotiation.cc:967] calling sasl_server_step()
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.065995 (+   271us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.066893 (+   898us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.066928 (+    35us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.066959 (+    31us) server_negotiation.cc:967] calling sasl_server_step()
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.067773 (+   814us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.068118 (+   345us) server_negotiation.cc:1035] Waiting for connection context
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.069453 (+  1335us) server_negotiation.cc:299] Negotiation successful
16:15:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:42.069895 (+   442us) negotiation.cc:327] Negotiation complete: OK
16:15:42.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":942,"thread_start_us":624,"threads_started":1}
16:15:42.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.074493 32601 heartbeater.cc:347] Connected to a master server at 127.31.89.126:36559
16:15:42.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.074991 32601 heartbeater.cc:464] Registering TS with master...
16:15:42.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.076845 32601 heartbeater.cc:511] Master 127.31.89.126:36559 requested a full tablet report, sending...
16:15:42.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.080384 32217 ts_manager.cc:194] Registered new tserver with Master: 20738c51f5c64978be629bb9a56d5d1e (127.31.89.65:40641)
16:15:42.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.082670 32217 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.31.89.65@KRBTEST.COM'} at 127.31.89.65:47075
16:15:42.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:42.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.66 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.66.keytab.
16:15:42.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.66 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.66.keytab.
16:15:42.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.087530 32101 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:42.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:42.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/wal
16:15:42.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/logs
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data/info.pb
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.66:0
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.89.66
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.66
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.89.126:36559
16:15:42.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.66.keytab
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.66
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:15:42.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:15:42.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.418059 32607 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:15:42.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.418773 32607 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:15:42.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.419564 32607 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:15:42.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.450707 32607 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:15:42.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.451143 32607 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:15:42.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.452217 32607 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.89.66
16:15:42.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.486276 32607 tablet_server_runner.cc:78] Tablet server non-default flags:
16:15:42.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/wal
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.66.keytab
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.66
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.66:0
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data/info.pb
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.66
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.89.126:36559
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:15:42.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.89.66
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/logs
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 15:14:16 UTC on ccycloud.quasar-uwbvwl.root.comops.site
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4580677
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
16:15:42.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.487712 32607 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:15:42.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:42.489105 32607 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:15:42.510 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.510283 32618 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
16:15:43.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.087673 32601 heartbeater.cc:503] Master 127.31.89.126:36559 was elected leader, sending a full tablet report...
16:15:43.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.510406 32615 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
16:15:43.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:42.510823 32614 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
16:15:43.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:43.698385 32616 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
16:15:43.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.698493 32607 server_base.cc:1029] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
16:15:43.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.702014 32607 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:15:43.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.704322 32607 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:15:43.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.705737 32607 hybrid_clock.cc:648] HybridClock initialized: now 1786464943705672 us; error 46 us; skew 500 ppm
16:15:43.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.706990 32607 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
16:15:43.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:43 dist-test-slave-rwrg krb5kdc[32122](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464943, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.89.66@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:15:43.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.715167 32607 init.cc:377] Logged in from keytab as kudu/127.31.89.66@KRBTEST.COM (short username kudu)
16:15:43.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.722903 32607 webserver.cc:460] Webserver started at http://127.31.89.66:41643/ using document root <none> and password file <none>
16:15:43.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.724133 32607 fs_manager.cc:359] Metadata directory not provided
16:15:43.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.724400 32607 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:15:43.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.725020 32607 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:15:43.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.732715 32607 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data/instance:
16:15:43.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "aed9a92b405145dc87f027c80753ce70"
16:15:43.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:43 on dist-test-slave-rwrg"
16:15:43.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.734336 32607 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/wal/instance:
16:15:43.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "aed9a92b405145dc87f027c80753ce70"
16:15:43.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:43 on dist-test-slave-rwrg"
16:15:43.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.744242 32607 fs_manager.cc:691] Time spent creating directory manager: real 0.009s	user 0.010s	sys 0.000s
16:15:43.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.751554 32631 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:15:43.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.752903 32607 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.007s	sys 0.000s
16:15:43.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.753280 32607 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data,/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/wal
16:15:43.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "aed9a92b405145dc87f027c80753ce70"
16:15:43.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:43 on dist-test-slave-rwrg"
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.753675 32607 fs_report.cc:383] FS layout report
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/wal
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/wal
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data/data
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:15:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:15:43.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.792214 32607 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:15:43.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.798552 32607 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
16:15:43.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.799129 32607 kserver.cc:163] Server-wide thread pool size limit: 3276
16:15:43.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.802313 32607 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
16:15:43.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.806768 32607 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
16:15:43.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.807004 32607 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
16:15:43.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.807327 32607 ts_tablet_manager.cc:610] Registered 0 tablets
16:15:43.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.807540 32607 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
16:15:43.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.991467 32607 rpc_server.cc:307] RPC server started. Bound to: 127.31.89.66:40327
16:15:43.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.991572   380 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.31.89.66:40327 every 8 connection(s)
16:15:43.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.994377 32607 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/data/info.pb
16:15:43.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.994985 32101 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu as pid 32607
16:15:43.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:43.995491 32101 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-1/wal/instance
16:15:44.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.89.67@KRBTEST.COM; defaulting to no policy
16:15:44.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:44.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.89.67@KRBTEST.COM" created.
16:15:44.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:44 dist-test-slave-rwrg krb5kdc[32122](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464943, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.89.66@KRBTEST.COM for kudu/127.31.89.126@KRBTEST.COM
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.042130   386 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.000408 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.31.89.66:46793 (local address 127.31.89.126:36559)
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.001885 (+  1477us) server_negotiation.cc:206] Beginning negotiation
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.001913 (+    28us) server_negotiation.cc:399] Waiting for connection header
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.004566 (+  2653us) server_negotiation.cc:407] Connection header received
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.012053 (+  7487us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.012090 (+    37us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:15:44.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.012329 (+   239us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.012482 (+   153us) server_negotiation.cc:226] Negotiated authn=SASL
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.016053 (+  3571us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.017213 (+  1160us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.020947 (+  3734us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.022069 (+  1122us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.022372 (+   303us) server_negotiation.cc:662] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.029719 (+  7347us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.029744 (+    25us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.029791 (+    47us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.029967 (+   176us) server_negotiation.cc:922] calling sasl_server_start()
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.034221 (+  4254us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.036111 (+  1890us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.036142 (+    31us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.036167 (+    25us) server_negotiation.cc:967] calling sasl_server_step()
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.036428 (+   261us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.037613 (+  1185us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.037640 (+    27us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.037661 (+    21us) server_negotiation.cc:967] calling sasl_server_step()
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.038365 (+   704us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.038714 (+   349us) server_negotiation.cc:1035] Waiting for connection context
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.040287 (+  1573us) server_negotiation.cc:299] Negotiation successful
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.040670 (+   383us) negotiation.cc:327] Negotiation complete: OK
16:15:44.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":892,"thread_start_us":565,"threads_started":1}
16:15:44.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.043969   389 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:15:44.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.000688 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.31.89.126:36559 (local address 127.31.89.66:46793)
16:15:44.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.004185 (+  3497us) negotiation.cc:107] Waiting for socket to connect
16:15:44.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.004311 (+   126us) client_negotiation.cc:174] Beginning negotiation
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.011583 (+  7272us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.012872 (+  1289us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.012915 (+    43us) client_negotiation.cc:394] Received NEGOTIATE response from server
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.014251 (+  1336us) client_negotiation.cc:189] Negotiated authn=SASL
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.015696 (+  1445us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.015771 (+    75us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.017486 (+  1715us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.017523 (+    37us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.020576 (+  3053us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.020643 (+    67us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.022718 (+  2075us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.022764 (+    46us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:15:44.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.023369 (+   605us) client_negotiation.cc:553] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.024716 (+  1347us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.024785 (+    69us) client_negotiation.cc:656] calling sasl_client_start()
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.029183 (+  4398us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.034603 (+  5420us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.034627 (+    24us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.034653 (+    26us) client_negotiation.cc:760] calling sasl_client_step()
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.035679 (+  1026us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.036807 (+  1128us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.036842 (+    35us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.036871 (+    29us) client_negotiation.cc:760] calling sasl_client_step()
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.037258 (+   387us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.038772 (+  1514us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.038828 (+    56us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.039508 (+   680us) client_negotiation.cc:769] Sending connection context
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.040075 (+   567us) client_negotiation.cc:240] Negotiation successful
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:44.040639 (+   564us) negotiation.cc:327] Negotiation complete: OK
16:15:44.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2889,"thread_start_us":1891,"threads_started":1}
16:15:44.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.047468   384 heartbeater.cc:347] Connected to a master server at 127.31.89.126:36559
16:15:44.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.048005   384 heartbeater.cc:464] Registering TS with master...
16:15:44.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.049496   384 heartbeater.cc:511] Master 127.31.89.126:36559 requested a full tablet report, sending...
16:15:44.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.052412 32217 ts_manager.cc:194] Registered new tserver with Master: aed9a92b405145dc87f027c80753ce70 (127.31.89.66:40327)
16:15:44.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.054606 32217 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.31.89.66@KRBTEST.COM'} at 127.31.89.66:46793
16:15:44.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:15:44.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.67 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.67.keytab.
16:15:44.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.89.67 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.67.keytab.
16:15:44.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.059506 32101 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/wal
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/logs
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data/info.pb
16:15:44.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.67:0
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.89.67
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.67
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.89.126:36559
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.67.keytab
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.67
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:15:44.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:15:44.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:15:44.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.382956   396 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:15:44.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.383455   396 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:15:44.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.384135   396 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:15:44.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.414739   396 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:15:44.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.415060   396 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:15:44.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.415990   396 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.89.67
16:15:44.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.452507   396 tablet_server_runner.cc:78] Tablet server non-default flags:
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.89.84:46845
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/wal
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kudu_127.31.89.67.keytab
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.89.67
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.89.67:0
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data/info.pb
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.89.67
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.89.126:36559
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.89.67
16:15:44.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/logs
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 15:14:16 UTC on ccycloud.quasar-uwbvwl.root.comops.site
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4580677
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
16:15:44.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.453696   396 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:15:44.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:44.455034   396 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:15:44.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.478492   414 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
16:15:45.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.059748   384 heartbeater.cc:503] Master 127.31.89.126:36559 was elected leader, sending a full tablet report...
16:15:45.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.479467   407 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
16:15:45.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:44.478582   411 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
16:15:45.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:45.669476   412 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
16:15:45.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.669646   396 server_base.cc:1029] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
16:15:45.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.674050   396 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:15:45.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.676417   396 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:15:45.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.677876   396 hybrid_clock.cc:648] HybridClock initialized: now 1786464945677841 us; error 41 us; skew 500 ppm
16:15:45.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.678798   396 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
16:15:45.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:45 dist-test-slave-rwrg krb5kdc[32122](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464945, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.89.67@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:15:45.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.685120   396 init.cc:377] Logged in from keytab as kudu/127.31.89.67@KRBTEST.COM (short username kudu)
16:15:45.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.690598   396 webserver.cc:460] Webserver started at http://127.31.89.67:43139/ using document root <none> and password file <none>
16:15:45.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.691493   396 fs_manager.cc:359] Metadata directory not provided
16:15:45.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.691679   396 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:15:45.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.692066   396 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:15:45.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.696759   396 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data/instance:
16:15:45.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9affbcff5e7e4a34bfe8cfb19b1a9494"
16:15:45.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:45 on dist-test-slave-rwrg"
16:15:45.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.697817   396 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/wal/instance:
16:15:45.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9affbcff5e7e4a34bfe8cfb19b1a9494"
16:15:45.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:45 on dist-test-slave-rwrg"
16:15:45.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.705169   396 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.004s	sys 0.003s
16:15:45.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.713263   429 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:15:45.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.714620   396 fs_manager.cc:725] Time spent opening block manager: real 0.005s	user 0.004s	sys 0.000s
16:15:45.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.714900   396 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data,/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/wal
16:15:45.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9affbcff5e7e4a34bfe8cfb19b1a9494"
16:15:45.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 16:15:45 on dist-test-slave-rwrg"
16:15:45.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.715301   396 fs_report.cc:383] FS layout report
16:15:45.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/wal
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/wal
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data/data
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:15:45.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:15:45.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.762471   396 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:15:45.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.767851   396 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
16:15:45.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.768242   396 kserver.cc:163] Server-wide thread pool size limit: 3276
16:15:45.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.770907   396 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
16:15:45.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.775064   396 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
16:15:45.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.775250   396 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
16:15:45.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.775430   396 ts_tablet_manager.cc:610] Registered 0 tablets
16:15:45.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.775738   396 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
16:15:45.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.921059   396 rpc_server.cc:307] RPC server started. Bound to: 127.31.89.67:36935
16:15:45.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.921204   641 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.31.89.67:36935 every 8 connection(s)
16:15:45.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.925423   396 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/data/info.pb
16:15:45.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.933486 32101 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu as pid 396
16:15:45.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:45.934072 32101 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/ts-2/wal/instance
16:15:46.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 16:15:46 dist-test-slave-rwrg krb5kdc[32122](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786464945, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.89.67@KRBTEST.COM for kudu/127.31.89.126@KRBTEST.COM
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.026938   653 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.937251 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.31.89.67:55409 (local address 127.31.89.126:36559)
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.940190 (+  2939us) server_negotiation.cc:206] Beginning negotiation
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.940224 (+    34us) server_negotiation.cc:399] Waiting for connection header
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.983847 (+ 43623us) server_negotiation.cc:407] Connection header received
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.993988 (+ 10141us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.994015 (+    27us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.994245 (+   230us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.994424 (+   179us) server_negotiation.cc:226] Negotiated authn=SASL
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.999242 (+  4818us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.001081 (+  1839us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.008369 (+  7288us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:15:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.009285 (+   916us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.009585 (+   300us) server_negotiation.cc:662] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.015054 (+  5469us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.015083 (+    29us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.015100 (+    17us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.015195 (+    95us) server_negotiation.cc:922] calling sasl_server_start()
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.018890 (+  3695us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020151 (+  1261us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020171 (+    20us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020185 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020375 (+   190us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.021252 (+   877us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:15:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.021270 (+    18us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.021284 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.022092 (+   808us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.022407 (+   315us) server_negotiation.cc:1035] Waiting for connection context
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.024197 (+  1790us) server_negotiation.cc:299] Negotiation successful
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.024667 (+   470us) negotiation.cc:327] Negotiation complete: OK
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"mutex_wait_us":41,"server-negotiator.queue_time_us":1470,"thread_start_us":4387,"threads_started":1}
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.027515   652 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.933174 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.31.89.126:36559 (local address 127.31.89.67:55409)
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.983089 (+ 49915us) negotiation.cc:107] Waiting for socket to connect
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.983322 (+   233us) client_negotiation.cc:174] Beginning negotiation
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.993506 (+ 10184us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.995605 (+  2099us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.995640 (+    35us) client_negotiation.cc:394] Received NEGOTIATE response from server
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.997122 (+  1482us) client_negotiation.cc:189] Negotiated authn=SASL
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.998845 (+  1723us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:45.998919 (+    74us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.001935 (+  3016us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.001965 (+    30us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.008082 (+  6117us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.008137 (+    55us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.009634 (+  1497us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.009670 (+    36us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.010176 (+   506us) client_negotiation.cc:553] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
16:15:46.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.011352 (+  1176us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.011456 (+   104us) client_negotiation.cc:656] calling sasl_client_start()
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.014619 (+  3163us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.019153 (+  4534us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.019174 (+    21us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.019206 (+    32us) client_negotiation.cc:760] calling sasl_client_step()
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.019899 (+   693us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020683 (+   784us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020701 (+    18us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020715 (+    14us) client_negotiation.cc:760] calling sasl_client_step()
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.020990 (+   275us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.022473 (+  1483us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.022521 (+    48us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.023176 (+   655us) client_negotiation.cc:769] Sending connection context
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.023716 (+   540us) client_negotiation.cc:240] Negotiation successful
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 16:15:46.024244 (+   528us) negotiation.cc:327] Negotiation complete: OK
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":48090,"thread_start_us":48491,"threads_started":1}
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.030231   647 heartbeater.cc:347] Connected to a master server at 127.31.89.126:36559
16:15:46.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.030736   647 heartbeater.cc:464] Registering TS with master...
16:15:46.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.031810   647 heartbeater.cc:511] Master 127.31.89.126:36559 requested a full tablet report, sending...
16:15:46.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.034695 32217 ts_manager.cc:194] Registered new tserver with Master: 9affbcff5e7e4a34bfe8cfb19b1a9494 (127.31.89.67:36935)
16:15:46.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.036816 32217 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.31.89.67@KRBTEST.COM'} at 127.31.89.67:55409
16:15:46.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.040970 32101 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
16:15:46.047 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
16:15:46.049 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

16:15:46.058 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

16:15:46.059 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

16:15:46.069 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.89.126"
      port: 36559
    }
    bound_http_address {
      host: "127.31.89.126"
      port: 42609
    }
  }
}

16:15:46.074 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

16:15:46.077 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.89.65"
      port: 40641
    }
    bound_http_address {
      host: "127.31.89.65"
      port: 41117
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.31.89.66"
      port: 40327
    }
    bound_http_address {
      host: "127.31.89.66"
      port: 41643
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.31.89.67"
      port: 36935
    }
    bound_http_address {
      host: "127.31.89.67"
      port: 43139
    }
  }
}

16:15:46.078 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
16:15:46.303 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskBrq1Pz/test-tmp/mini-kudu-cluster2108397651721512024/krb5kdc/krb5cc
16:15:46.313 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
16:15:46.326 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
16:15:46.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.329941 32101 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu with pid 32323
16:15:46.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:46.373234 32191 connection.cc:535] server connection from 127.31.89.65:47075 recv error: Network error: failed to read from TLS socket (remote: 127.31.89.65:47075): Connection reset by peer (error 104)
16:15:46.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.373579 32101 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu with pid 32607
16:15:46.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:46.432595 32191 connection.cc:535] server connection from 127.31.89.66:46793 recv error: Network error: failed to read from TLS socket (remote: 127.31.89.66:46793): Connection reset by peer (error 104)
16:15:46.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.433187 32101 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu with pid 396
16:15:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:46.474756 32189 connection.cc:535] server connection from 127.31.89.67:55409 recv error: Network error: failed to read from TLS socket (remote: 127.31.89.67:55409): Connection reset by peer (error 104)
16:15:46.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 16:15:46.475011 32101 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskBrq1Pz/build/tsan/bin/kudu with pid 32157
16:15:46.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 16:15:46.508100 32119 connection.cc:535] client connection to 127.31.89.126:36559 recv error: Network error: failed to read from TLS socket (remote: 127.31.89.126:36559): Connection reset by peer (error 104)
16:15:47.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T16:15:47Z chronyd exiting

Time: 18.307

OK (1 test)

