JUnit version 4.13.2
.05:22:33.386 [DEBUG - main] (TestNegotiationTLSv13.java:108) enabled TLS protocols: TLSv1.3 TLSv1.2 TLSv1.1 TLSv1
05:22:33.389 [DEBUG - main] (TestNegotiationTLSv13.java:110) supported TLS protocols: TLSv1.3 TLSv1.2 SSLv3 TLSv1.1 SSLv2Hello TLSv1
05:22:33.396 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-task7Mr1Sa/test-tmp
05:22:33.398 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
05:22:33.401 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-task7Mr1Sa/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
05:22:33.699 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 0
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477"
  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 {
  }
}

05:22:33.823 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
05:22:33.824 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

05:22:33.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.827553  7238 mini_oidc.cc:152] Starting JWKS server
05:22:33.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.831265  7238 webserver.cc:460] Webserver started at http://127.0.0.1:46673/ using document root <none> and password file <none>
05:22:33.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.831736  7238 mini_oidc.cc:189] Starting OIDC Discovery server
05:22:33.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.833410  7238 webserver.cc:460] Webserver started at http://127.0.0.1:38341/ using document root <none> and password file <none>
05:22:33.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:33Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
05:22:33.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:33Z Disabled control of system clock
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.889580  7238 external_mini_cluster.cc:1326] Running /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/wal
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/data
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:33.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/logs
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/data/info.pb
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
05:22:33.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.17.190:42139
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.17.190
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.17.148:46765
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.7.17.190:42139
05:22:33.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
05:22:33.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46673/jwks.json
05:22:33.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
05:22:33.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
05:22:34.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.186614  7251 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:34.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.187311  7251 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:34.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.188043  7251 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:34.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.218174  7251 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
05:22:34.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.218568  7251 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:34.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.218900  7251 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
05:22:34.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.219257  7251 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
05:22:34.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.219583  7251 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
05:22:34.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.219834  7251 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:46673/jwks.json
05:22:34.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:34.254832  7251 master_runner.cc:386] Master server non-default flags:
05:22:34.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:34.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.17.148:46765
05:22:34.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:34.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/data
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/wal
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.7.17.190:42139
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.17.190:42139
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
05:22:34.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:34.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
05:22:34.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46673/jwks.json
05:22:34.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
05:22:34.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:34.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/data/info.pb
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.17.190
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/logs
05:22:34.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:34.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:34.256343  7251 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:34.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:34.258664  7251 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:34.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.277706  7258 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
05:22:35.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.277889  7260 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
05:22:35.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.278893  7257 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
05:22:35.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:34.279047  7251 server_base.cc:1034] running on GCE node
05:22:35.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.472724  7251 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
05:22:35.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.472992  7251 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
05:22:35.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.473234  7251 hybrid_clock.cc:648] HybridClock initialized: now 1783488155473202 us; error 0 us; skew 500 ppm
05:22:35.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.474272  7251 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:35.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.481170  7251 webserver.cc:460] Webserver started at http://127.7.17.190:39817/ using document root <none> and password file <none>
05:22:35.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.482234  7251 fs_manager.cc:359] Metadata directory not provided
05:22:35.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.482476  7251 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:35.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.482996  7251 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:35.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.487831  7251 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/data/instance:
05:22:35.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "406f222d266a40f7a6b62609757be9d1"
05:22:35.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:35 on dist-test-slave-q99k"
05:22:35.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.489151  7251 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/wal/instance:
05:22:35.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "406f222d266a40f7a6b62609757be9d1"
05:22:35.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:35 on dist-test-slave-q99k"
05:22:35.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.496286  7251 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.002s	sys 0.004s
05:22:35.502 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.501976  7266 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:35.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.503015  7251 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.002s
05:22:35.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.503366  7251 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/data,/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/wal
05:22:35.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "406f222d266a40f7a6b62609757be9d1"
05:22:35.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:35 on dist-test-slave-q99k"
05:22:35.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.503755  7251 fs_report.cc:383] FS layout report
05:22:35.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:35.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/wal
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/wal
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster4724223662910741477/master-0/data/data
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:35.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:35.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:35.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:35.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:35.562 [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
05:22:36.591 [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-task7Mr1Sa/build/tsan/bin/kudu: process exited with non-zero status 1"
}

05:22:37.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:37Z chronyd exiting
05:22:38.792 [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-task7Mr1Sa/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]
05:22:38.800 [INFO - main] (RandomUtils.java:49) Using random seed: 1783488158800
05:22:38.807 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
05:22:38.849 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
05:22:38.850 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-task7Mr1Sa/test-tmp
05:22:38.850 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
05:22:38.850 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-task7Mr1Sa/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
05:22:38.853 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247"
  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 {
  }
}

05:22:39.174 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
05:22:39.175 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

05:22:39.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.178071  7269 mini_oidc.cc:152] Starting JWKS server
05:22:39.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.181527  7269 webserver.cc:460] Webserver started at http://127.0.0.1:42973/ using document root <none> and password file <none>
05:22:39.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.181949  7269 mini_oidc.cc:189] Starting OIDC Discovery server
05:22:39.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.183597  7269 webserver.cc:460] Webserver started at http://127.0.0.1:45659/ using document root <none> and password file <none>
05:22:39.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
05:22:39.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/principal' for realm 'KRBTEST.COM',
05:22:39.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
05:22:39.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:39 dist-test-slave-q99k krb5kdc[7280](info): setting up network...
05:22:39.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
05:22:39.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:39 dist-test-slave-q99k krb5kdc[7280](info): set up 2 sockets
05:22:39.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:39 dist-test-slave-q99k krb5kdc[7280](info): commencing operation
05:22:39.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
05:22:40.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.260430  7269 mini_kdc.cc:121] Time spent starting KDC: real 1.053s	user 0.000s	sys 0.019s
05:22:40.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
05:22:40.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:40.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
05:22:40.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
05:22:40.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:40.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
05:22:40.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
05:22:40.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:40.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
05:22:40.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:40.362 [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-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/test-user.keytab.
05:22:40.362 [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-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/test-user.keytab.
05:22:40.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:40 dist-test-slave-q99k krb5kdc[7280](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488160, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:40.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
05:22:40.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:40Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
05:22:40.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:40Z Disabled control of system clock
05:22:40.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.7.25.126@KRBTEST.COM; defaulting to no policy
05:22:40.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:40.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.7.25.126@KRBTEST.COM" created.
05:22:40.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:40.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.126 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.126.keytab.
05:22:40.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.126 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.126.keytab.
05:22:40.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.450974  7269 external_mini_cluster.cc:1326] Running /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:40.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:40.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/wal
05:22:40.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data
05:22:40.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/logs
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data/info.pb
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:40.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.126:33633
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.126
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.126.keytab
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.126
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:40.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
05:22:40.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.7.25.126:33633
05:22:40.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
05:22:40.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:42973/jwks.json
05:22:40.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
05:22:40.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
05:22:40.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:40.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.753886  7293 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:40.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.754611  7293 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:40.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.755424  7293 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:40.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.786569  7293 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
05:22:40.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.787019  7293 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
05:22:40.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.787459  7293 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:40.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.787810  7293 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
05:22:40.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.788164  7293 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
05:22:40.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.788511  7293 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
05:22:40.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.788784  7293 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:42973/jwks.json
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.824575  7293 master_runner.cc:386] Master server non-default flags:
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/wal
05:22:40.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.7.25.126:33633
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.126.keytab
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.126
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
05:22:40.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.126:33633
05:22:40.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
05:22:40.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:40.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
05:22:40.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:42973/jwks.json
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data/info.pb
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.126
05:22:40.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/logs
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:40.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:40.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:40.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:40.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:40.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:40.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.826057  7293 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:40.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.827476  7293 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:40.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.845309  7300 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
05:22:42.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.845326  7299 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
05:22:42.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:40.845384  7302 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
05:22:42.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.847261  7293 server_base.cc:1034] running on GCE node
05:22:42.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.036024  7293 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:42.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.039166  7293 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:42.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.040719  7293 hybrid_clock.cc:648] HybridClock initialized: now 1783488162040664 us; error 63 us; skew 500 ppm
05:22:42.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.041915  7293 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:42.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:42 dist-test-slave-q99k krb5kdc[7280](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488162, etypes {rep=17 tkt=17 ses=17}, kudu/127.7.25.126@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:42.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.048552  7293 init.cc:377] Logged in from keytab as kudu/127.7.25.126@KRBTEST.COM (short username kudu)
05:22:42.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.054032  7293 webserver.cc:460] Webserver started at http://127.7.25.126:37943/ using document root <none> and password file <none>
05:22:42.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.055096  7293 fs_manager.cc:359] Metadata directory not provided
05:22:42.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.055343  7293 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:42.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.055897  7293 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:42.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.060840  7293 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data/instance:
05:22:42.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0c5ea402696440a6bc1df979d96d3a0a"
05:22:42.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:42 on dist-test-slave-q99k"
05:22:42.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.062268  7293 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/wal/instance:
05:22:42.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0c5ea402696440a6bc1df979d96d3a0a"
05:22:42.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:42 on dist-test-slave-q99k"
05:22:42.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.069794  7293 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.001s
05:22:42.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.075556  7310 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:42.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.076686  7293 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.001s
05:22:42.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.077055  7293 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data,/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/wal
05:22:42.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0c5ea402696440a6bc1df979d96d3a0a"
05:22:42.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:42 on dist-test-slave-q99k"
05:22:42.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.077487  7293 fs_report.cc:383] FS layout report
05:22:42.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:42.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/wal
05:22:42.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/wal
05:22:42.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data/data
05:22:42.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:42.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:42.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:42.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.117916  7293 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:42.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.123915  7293 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
05:22:42.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.124441  7293 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:42.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.193094  7293 rpc_server.cc:307] RPC server started. Bound to: 127.7.25.126:33633
05:22:42.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.193176  7361 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.7.25.126:33633 every 8 connection(s)
05:22:42.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.195916  7293 server_base.cc:1166] Dumped server information to /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/data/info.pb
05:22:42.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.201146  7362 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
05:22:42.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.203508  7269 external_mini_cluster.cc:1388] Started /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu as pid 7293
05:22:42.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.204051  7269 external_mini_cluster.cc:1402] Reading /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/master-0/wal/instance
05:22:42.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.222247  7362 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a: Bootstrap starting.
05:22:42.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.229035  7362 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a: Neither blocks nor log segments found. Creating new log.
05:22:42.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.230830  7362 log.cc:824] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a: Log is configured to *not* fsync() on all Append() calls
05:22:42.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.236060  7362 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a: No bootstrap required, opened a new log
05:22:42.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:42 dist-test-slave-q99k krb5kdc[7280](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488160, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.7.25.126@KRBTEST.COM
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.255666  7364 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.210767 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:45910 (local address 127.7.25.126:33633)
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.213492 (+  2725us) server_negotiation.cc:206] Beginning negotiation
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.213533 (+    41us) server_negotiation.cc:399] Waiting for connection header
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.214896 (+  1363us) server_negotiation.cc:407] Connection header received
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.226384 (+ 11488us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.226447 (+    63us) server_negotiation.cc:461] Received NEGOTIATE request from client
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.226957 (+   510us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.227456 (+   499us) server_negotiation.cc:226] Negotiated authn=SASL
05:22:42.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.231218 (+  3762us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:42.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.232931 (+  1713us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:42.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.235208 (+  2277us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:42.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.236082 (+   874us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:42.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.236430 (+   348us) 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
05:22:42.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.243484 (+  7054us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
05:22:42.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.243508 (+    24us) server_negotiation.cc:881] Received SASL_INITIATE request from client
05:22:42.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.243540 (+    32us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.243648 (+   108us) server_negotiation.cc:922] calling sasl_server_start()
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.247844 (+  4196us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.249330 (+  1486us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.249353 (+    23us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.249369 (+    16us) server_negotiation.cc:967] calling sasl_server_step()
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.249689 (+   320us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.250550 (+   861us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.250572 (+    22us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.250586 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.251288 (+   702us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.251802 (+   514us) server_negotiation.cc:1035] Waiting for connection context
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.252952 (+  1150us) server_negotiation.cc:299] Negotiation successful
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:42.253433 (+   481us) negotiation.cc:327] Negotiation complete: OK
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":2136,"thread_start_us":1696,"threads_started":1}
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.257303  7362 raft_consensus.cc:348] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0c5ea402696440a6bc1df979d96d3a0a" member_type: VOTER last_known_addr { host: "127.7.25.126" port: 33633 } }
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.258215  7362 raft_consensus.cc:374] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
05:22:42.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.258498  7362 raft_consensus.cc:729] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0c5ea402696440a6bc1df979d96d3a0a, State: Initialized, Role: FOLLOWER
05:22:42.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.259377  7362 consensus_queue.cc:260] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [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: "0c5ea402696440a6bc1df979d96d3a0a" member_type: VOTER last_known_addr { host: "127.7.25.126" port: 33633 } }
05:22:42.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.260052  7362 raft_consensus.cc:388] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
05:22:42.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.260355  7362 raft_consensus.cc:482] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
05:22:42.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.260743  7362 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 0 FOLLOWER]: Advancing to term 1
05:22:42.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.265128  7362 raft_consensus.cc:504] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0c5ea402696440a6bc1df979d96d3a0a" member_type: VOTER last_known_addr { host: "127.7.25.126" port: 33633 } }
05:22:42.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.266059  7362 leader_election.cc:302] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [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: 0c5ea402696440a6bc1df979d96d3a0a; no voters: 
05:22:42.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.267915  7362 leader_election.cc:288] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [CANDIDATE]: Term 1 election: Requested vote from peers 
05:22:42.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.268308  7367 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 1 FOLLOWER]: Leader election won for term 1
05:22:42.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.271649  7367 raft_consensus.cc:686] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [term 1 LEADER]: Becoming Leader. State: Replica: 0c5ea402696440a6bc1df979d96d3a0a, State: Running, Role: LEADER
05:22:42.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.272676  7362 sys_catalog.cc:564] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [sys.catalog]: configured and running, proceeding with master startup.
05:22:42.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.272455  7367 consensus_queue.cc:237] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [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: "0c5ea402696440a6bc1df979d96d3a0a" member_type: VOTER last_known_addr { host: "127.7.25.126" port: 33633 } }
05:22:42.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.288300  7368 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "0c5ea402696440a6bc1df979d96d3a0a" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0c5ea402696440a6bc1df979d96d3a0a" member_type: VOTER last_known_addr { host: "127.7.25.126" port: 33633 } } }
05:22:42.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.289160  7368 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [sys.catalog]: This master's current role is: LEADER
05:22:42.290 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.289950  7369 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [sys.catalog]: SysCatalogTable state changed. Reason: New leader 0c5ea402696440a6bc1df979d96d3a0a. Latest consensus state: current_term: 1 leader_uuid: "0c5ea402696440a6bc1df979d96d3a0a" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0c5ea402696440a6bc1df979d96d3a0a" member_type: VOTER last_known_addr { host: "127.7.25.126" port: 33633 } } }
05:22:42.290 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.290518  7369 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a [sys.catalog]: This master's current role is: LEADER
05:22:42.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.292824  7377 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
05:22:42.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.303057  7377 catalog_manager.cc:1484] Initializing Kudu cluster ID...
05:22:42.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.321010  7377 catalog_manager.cc:1347] Generated new cluster ID: 8825224d4ae9476bab316a7df90c466b
05:22:42.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.321316  7377 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
05:22:42.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.351745  7377 catalog_manager.cc:1370] Generated new certificate authority record
05:22:42.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.354370  7377 catalog_manager.cc:1504] Loading token signing keys...
05:22:42.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.368135  7377 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 0c5ea402696440a6bc1df979d96d3a0a: Generated new TSK 0
05:22:42.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.369149  7377 catalog_manager.cc:1514] Initializing in-progress tserver states...
05:22:42.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.7.25.65@KRBTEST.COM; defaulting to no policy
05:22:42.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:42.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.7.25.65@KRBTEST.COM" created.
05:22:42.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:42.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.65 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.65.keytab.
05:22:42.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.65 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.65.keytab.
05:22:42.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.429195  7269 external_mini_cluster.cc:1326] Running /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:42.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:42.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/wal
05:22:42.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/logs
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data/info.pb
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:42.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.65:0
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.7.25.65
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.65
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.7.25.126:33633
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.65.keytab
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.65
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:42.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:42.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
05:22:42.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
05:22:42.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:42.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.752091  7388 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:42.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.752563  7388 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:42.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.753137  7388 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:42.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.784487  7388 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
05:22:42.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.785025  7388 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:42.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.785995  7388 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.7.25.65
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.821662  7388 tablet_server_runner.cc:78] Tablet server non-default flags:
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/wal
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.65.keytab
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.65
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.65:0
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data/info.pb
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.65
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.7.25.126:33633
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.7.25.65
05:22:42.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/logs
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.822784  7388 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:42.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.823730  7388 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:42.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.849282  7395 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
05:22:44.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.853989  7397 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
05:22:44.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.072626  7396 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
05:22:44.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:42.853178  7394 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
05:22:44.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.072664  7388 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
05:22:44.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.077914  7388 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:44.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.080651  7388 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:44.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.082096  7388 hybrid_clock.cc:648] HybridClock initialized: now 1783488164082031 us; error 79 us; skew 500 ppm
05:22:44.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.082871  7388 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:44.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:44 dist-test-slave-q99k krb5kdc[7280](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488164, etypes {rep=17 tkt=17 ses=17}, kudu/127.7.25.65@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:44.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.089716  7388 init.cc:377] Logged in from keytab as kudu/127.7.25.65@KRBTEST.COM (short username kudu)
05:22:44.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.095942  7388 webserver.cc:460] Webserver started at http://127.7.25.65:42047/ using document root <none> and password file <none>
05:22:44.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.097281  7388 fs_manager.cc:359] Metadata directory not provided
05:22:44.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.097591  7388 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:44.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.098145  7388 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:44.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.105348  7388 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data/instance:
05:22:44.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e16f3ae0d3234b63ad219c3462dc5173"
05:22:44.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:44 on dist-test-slave-q99k"
05:22:44.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.106813  7388 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/wal/instance:
05:22:44.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e16f3ae0d3234b63ad219c3462dc5173"
05:22:44.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:44 on dist-test-slave-q99k"
05:22:44.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.114961  7388 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.005s	sys 0.003s
05:22:44.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.120970  7405 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:44.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.123018  7388 fs_manager.cc:725] Time spent opening block manager: real 0.005s	user 0.003s	sys 0.000s
05:22:44.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.123402  7388 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data,/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/wal
05:22:44.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e16f3ae0d3234b63ad219c3462dc5173"
05:22:44.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:44 on dist-test-slave-q99k"
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.123799  7388 fs_report.cc:383] FS layout report
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/wal
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/wal
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data/data
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:44.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:44.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:44.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:44.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:44.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:44.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:44.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:44.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.158548  7388 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:44.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.163978  7388 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
05:22:44.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.164386  7388 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:44.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.167407  7388 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
05:22:44.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.171257  7388 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
05:22:44.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.171458  7388 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
05:22:44.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.171648  7388 ts_tablet_manager.cc:610] Registered 0 tablets
05:22:44.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.171766  7388 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
05:22:44.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.307462  7388 rpc_server.cc:307] RPC server started. Bound to: 127.7.25.65:42833
05:22:44.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.307566  7517 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.7.25.65:42833 every 8 connection(s)
05:22:44.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.310060  7388 server_base.cc:1166] Dumped server information to /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/data/info.pb
05:22:44.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.319520  7269 external_mini_cluster.cc:1388] Started /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu as pid 7388
05:22:44.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.320003  7269 external_mini_cluster.cc:1402] Reading /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-0/wal/instance
05:22:44.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:44 dist-test-slave-q99k krb5kdc[7280](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488164, etypes {rep=17 tkt=17 ses=17}, kudu/127.7.25.65@KRBTEST.COM for kudu/127.7.25.126@KRBTEST.COM
05:22:44.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.7.25.66@KRBTEST.COM; defaulting to no policy
05:22:44.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:44.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.7.25.66@KRBTEST.COM" created.
05:22:44.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.354481  7520 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.314217 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.7.25.65:47689 (local address 127.7.25.126:33633)
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.315758 (+  1541us) server_negotiation.cc:206] Beginning negotiation
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.315790 (+    32us) server_negotiation.cc:399] Waiting for connection header
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.317255 (+  1465us) server_negotiation.cc:407] Connection header received
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.323501 (+  6246us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.323532 (+    31us) server_negotiation.cc:461] Received NEGOTIATE request from client
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.323716 (+   184us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.323898 (+   182us) server_negotiation.cc:226] Negotiated authn=SASL
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.326791 (+  2893us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.327764 (+   973us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.331320 (+  3556us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.331939 (+   619us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.332129 (+   190us) 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
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.342113 (+  9984us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.342134 (+    21us) server_negotiation.cc:881] Received SASL_INITIATE request from client
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.342154 (+    20us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.342256 (+   102us) server_negotiation.cc:922] calling sasl_server_start()
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.347402 (+  5146us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.348982 (+  1580us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.349019 (+    37us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.349048 (+    29us) server_negotiation.cc:967] calling sasl_server_step()
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.349329 (+   281us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.350333 (+  1004us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.350352 (+    19us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.350365 (+    13us) server_negotiation.cc:967] calling sasl_server_step()
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.350852 (+   487us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.351080 (+   228us) server_negotiation.cc:1035] Waiting for connection context
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.352744 (+  1664us) server_negotiation.cc:299] Negotiation successful
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.353010 (+   266us) negotiation.cc:327] Negotiation complete: OK
05:22:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":1002,"thread_start_us":741,"threads_started":1}
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.356247  7521 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.314581 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.7.25.126:33633 (local address 127.7.25.65:47689)
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.317007 (+  2426us) negotiation.cc:107] Waiting for socket to connect
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.317077 (+    70us) client_negotiation.cc:174] Beginning negotiation
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.323109 (+  6032us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.324199 (+  1090us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.324236 (+    37us) client_negotiation.cc:394] Received NEGOTIATE response from server
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.325208 (+   972us) client_negotiation.cc:189] Negotiated authn=SASL
05:22:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.326473 (+  1265us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
05:22:44.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.326540 (+    67us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
05:22:44.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.328041 (+  1501us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
05:22:44.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.328078 (+    37us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
05:22:44.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.330958 (+  2880us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
05:22:44.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.331019 (+    61us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.332280 (+  1261us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.332324 (+    44us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.332843 (+   519us) 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
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.338122 (+  5279us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.338187 (+    65us) client_negotiation.cc:656] calling sasl_client_start()
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.341399 (+  3212us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.347772 (+  6373us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.347795 (+    23us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.347821 (+    26us) client_negotiation.cc:760] calling sasl_client_step()
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.348542 (+   721us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.349631 (+  1089us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.349649 (+    18us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.349663 (+    14us) client_negotiation.cc:760] calling sasl_client_step()
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.350014 (+   351us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.351181 (+  1167us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.351228 (+    47us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.351877 (+   649us) client_negotiation.cc:769] Sending connection context
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.352421 (+   544us) client_negotiation.cc:240] Negotiation successful
05:22:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:44.352945 (+   524us) negotiation.cc:327] Negotiation complete: OK
05:22:44.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":1976,"thread_start_us":1229,"threads_started":1}
05:22:44.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.359990  7518 heartbeater.cc:347] Connected to a master server at 127.7.25.126:33633
05:22:44.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.360461  7518 heartbeater.cc:464] Registering TS with master...
05:22:44.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.361687  7518 heartbeater.cc:511] Master 127.7.25.126:33633 requested a full tablet report, sending...
05:22:44.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.364843  7327 ts_manager.cc:194] Registered new tserver with Master: e16f3ae0d3234b63ad219c3462dc5173 (127.7.25.65:42833)
05:22:44.367 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.367312  7327 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.7.25.65@KRBTEST.COM'} at 127.7.25.65:47689
05:22:44.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:44.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.66 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.66.keytab.
05:22:44.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.66 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.66.keytab.
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.374181  7269 external_mini_cluster.cc:1326] Running /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/wal
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:44.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/logs
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data/info.pb
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.66:0
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.7.25.66
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.66
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:44.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.7.25.126:33633
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.66.keytab
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.66
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
05:22:44.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:44.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.686611  7524 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:44.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.687084  7524 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:44.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.687651  7524 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:44.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.718483  7524 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
05:22:44.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.718798  7524 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:44.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.719635  7524 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.7.25.66
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.753983  7524 tablet_server_runner.cc:78] Tablet server non-default flags:
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/wal
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.66.keytab
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.66
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.66:0
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:44.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data/info.pb
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.66
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.7.25.126:33633
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.7.25.66
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/logs
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:44.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.755100  7524 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:44.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.756207  7524 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:44.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.773980  7533 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
05:22:45.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.371636  7518 heartbeater.cc:503] Master 127.7.25.126:33633 was elected leader, sending a full tablet report...
05:22:46.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.773988  7531 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
05:22:46.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.775452  7530 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
05:22:46.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.776319  7524 server_base.cc:1034] running on GCE node
05:22:46.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.011201  7524 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:46.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.013528  7524 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:46.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.014916  7524 hybrid_clock.cc:648] HybridClock initialized: now 1783488166014884 us; error 40 us; skew 500 ppm
05:22:46.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.015749  7524 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:46.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:46 dist-test-slave-q99k krb5kdc[7280](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488166, etypes {rep=17 tkt=17 ses=17}, kudu/127.7.25.66@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:46.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.021684  7524 init.cc:377] Logged in from keytab as kudu/127.7.25.66@KRBTEST.COM (short username kudu)
05:22:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.026801  7524 webserver.cc:460] Webserver started at http://127.7.25.66:39795/ using document root <none> and password file <none>
05:22:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.027630  7524 fs_manager.cc:359] Metadata directory not provided
05:22:46.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.027827  7524 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:46.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.028203  7524 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:46.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.032903  7524 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data/instance:
05:22:46.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "72dfc36fce4448e5a21d4bc2bc0aa680"
05:22:46.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:46 on dist-test-slave-q99k"
05:22:46.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.034013  7524 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/wal/instance:
05:22:46.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "72dfc36fce4448e5a21d4bc2bc0aa680"
05:22:46.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:46 on dist-test-slave-q99k"
05:22:46.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.040982  7524 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.006s	sys 0.000s
05:22:46.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.046416  7541 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:46.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.047659  7524 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.000s
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.047977  7524 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data,/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/wal
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "72dfc36fce4448e5a21d4bc2bc0aa680"
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:46 on dist-test-slave-q99k"
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.048301  7524 fs_report.cc:383] FS layout report
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/wal
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/wal
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data/data
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:46.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:46.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:46.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:46.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:46.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:46.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:46.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.084944  7524 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:46.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.090593  7524 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
05:22:46.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.090993  7524 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:46.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.093357  7524 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
05:22:46.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.097299  7524 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
05:22:46.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.097539  7524 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.001s
05:22:46.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.097843  7524 ts_tablet_manager.cc:610] Registered 0 tablets
05:22:46.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.097973  7524 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
05:22:46.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.236985  7524 rpc_server.cc:307] RPC server started. Bound to: 127.7.25.66:46451
05:22:46.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.237094  7653 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.7.25.66:46451 every 8 connection(s)
05:22:46.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.239503  7524 server_base.cc:1166] Dumped server information to /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/data/info.pb
05:22:46.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.241909  7269 external_mini_cluster.cc:1388] Started /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu as pid 7524
05:22:46.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.242442  7269 external_mini_cluster.cc:1402] Reading /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-1/wal/instance
05:22:46.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.7.25.67@KRBTEST.COM; defaulting to no policy
05:22:46.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:46.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.7.25.67@KRBTEST.COM" created.
05:22:46.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:46 dist-test-slave-q99k krb5kdc[7280](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488166, etypes {rep=17 tkt=17 ses=17}, kudu/127.7.25.66@KRBTEST.COM for kudu/127.7.25.126@KRBTEST.COM
05:22:46.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.284525  7656 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.244368 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.7.25.66:42947 (local address 127.7.25.126:33633)
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.245871 (+  1503us) server_negotiation.cc:206] Beginning negotiation
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.245903 (+    32us) server_negotiation.cc:399] Waiting for connection header
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.247711 (+  1808us) server_negotiation.cc:407] Connection header received
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.255193 (+  7482us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.255230 (+    37us) server_negotiation.cc:461] Received NEGOTIATE request from client
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.255478 (+   248us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.255687 (+   209us) server_negotiation.cc:226] Negotiated authn=SASL
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.259001 (+  3314us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.260146 (+  1145us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.263655 (+  3509us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.264307 (+   652us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.264503 (+   196us) 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
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.272349 (+  7846us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.272390 (+    41us) server_negotiation.cc:881] Received SASL_INITIATE request from client
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.272428 (+    38us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.272599 (+   171us) server_negotiation.cc:922] calling sasl_server_start()
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.277176 (+  4577us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.279150 (+  1974us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.279170 (+    20us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.279184 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.279385 (+   201us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.280478 (+  1093us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.280497 (+    19us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.280511 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
05:22:46.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.280997 (+   486us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
05:22:46.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.281213 (+   216us) server_negotiation.cc:1035] Waiting for connection context
05:22:46.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.282859 (+  1646us) server_negotiation.cc:299] Negotiation successful
05:22:46.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.283143 (+   284us) negotiation.cc:327] Negotiation complete: OK
05:22:46.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":887,"thread_start_us":630,"threads_started":1}
05:22:46.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.286579  7657 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
05:22:46.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.244547 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.7.25.126:33633 (local address 127.7.25.66:42947)
05:22:46.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.247448 (+  2901us) negotiation.cc:107] Waiting for socket to connect
05:22:46.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.247542 (+    94us) client_negotiation.cc:174] Beginning negotiation
05:22:46.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.254702 (+  7160us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
05:22:46.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.256043 (+  1341us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
05:22:46.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.256085 (+    42us) client_negotiation.cc:394] Received NEGOTIATE response from server
05:22:46.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.257376 (+  1291us) client_negotiation.cc:189] Negotiated authn=SASL
05:22:46.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.258652 (+  1276us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
05:22:46.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.258727 (+    75us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
05:22:46.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.260426 (+  1699us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
05:22:46.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.260463 (+    37us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.263365 (+  2902us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.263432 (+    67us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.264646 (+  1214us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.264691 (+    45us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.265247 (+   556us) 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
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.266529 (+  1282us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.266595 (+    66us) client_negotiation.cc:656] calling sasl_client_start()
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.271714 (+  5119us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.277633 (+  5919us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.277670 (+    37us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.277715 (+    45us) client_negotiation.cc:760] calling sasl_client_step()
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.278799 (+  1084us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.279707 (+   908us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.279743 (+    36us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.279775 (+    32us) client_negotiation.cc:760] calling sasl_client_step()
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.280169 (+   394us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.281327 (+  1158us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.281377 (+    50us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.282076 (+   699us) client_negotiation.cc:769] Sending connection context
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.282638 (+   562us) client_negotiation.cc:240] Negotiation successful
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:46.283163 (+   525us) negotiation.cc:327] Negotiation complete: OK
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2376,"thread_start_us":1595,"threads_started":1}
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.289117  7654 heartbeater.cc:347] Connected to a master server at 127.7.25.126:33633
05:22:46.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.289621  7654 heartbeater.cc:464] Registering TS with master...
05:22:46.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.290889  7654 heartbeater.cc:511] Master 127.7.25.126:33633 requested a full tablet report, sending...
05:22:46.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.293726  7327 ts_manager.cc:194] Registered new tserver with Master: 72dfc36fce4448e5a21d4bc2bc0aa680 (127.7.25.66:46451)
05:22:46.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.295522  7327 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.7.25.66@KRBTEST.COM'} at 127.7.25.66:42947
05:22:46.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:46.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.67 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.67.keytab.
05:22:46.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.7.25.67 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.67.keytab.
05:22:46.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.301574  7269 external_mini_cluster.cc:1326] Running /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/wal
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/logs
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data/info.pb
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.67:0
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.7.25.67
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.67
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.7.25.126:33633
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.67.keytab
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.67
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
05:22:46.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:46.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.602437  7660 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:46.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.602962  7660 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:46.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.603632  7660 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:46.638 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.638610  7660 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
05:22:46.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.639045  7660 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:46.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.640072  7660 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.7.25.67
05:22:46.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.676179  7660 tablet_server_runner.cc:78] Tablet server non-default flags:
05:22:46.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.7.25.84:36937
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/wal
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kudu_127.7.25.67.keytab
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.7.25.67
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.7.25.67:0
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data/info.pb
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.7.25.67
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.7.25.126:33633
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.7.25.67
05:22:46.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/logs
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.677601  7660 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:46.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.678975  7660 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:46.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.700122  7666 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
05:22:47.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.300169  7654 heartbeater.cc:503] Master 127.7.25.126:33633 was elected leader, sending a full tablet report...
05:22:47.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.700152  7667 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
05:22:47.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:46.702601  7660 server_base.cc:1034] running on GCE node
05:22:47.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:46.700976  7669 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
05:22:47.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.889293  7660 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:47.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.891566  7660 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:47.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.892911  7660 hybrid_clock.cc:648] HybridClock initialized: now 1783488167892875 us; error 57 us; skew 500 ppm
05:22:47.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.893743  7660 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:47.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:47 dist-test-slave-q99k krb5kdc[7280](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488167, etypes {rep=17 tkt=17 ses=17}, kudu/127.7.25.67@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:47.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.899595  7660 init.cc:377] Logged in from keytab as kudu/127.7.25.67@KRBTEST.COM (short username kudu)
05:22:47.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.905201  7660 webserver.cc:460] Webserver started at http://127.7.25.67:33873/ using document root <none> and password file <none>
05:22:47.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.906116  7660 fs_manager.cc:359] Metadata directory not provided
05:22:47.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.906301  7660 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:47.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.906680  7660 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:47.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.911338  7660 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data/instance:
05:22:47.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ec96d7f943684e34867ce8f67b89288e"
05:22:47.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:47 on dist-test-slave-q99k"
05:22:47.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.912403  7660 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/wal/instance:
05:22:47.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ec96d7f943684e34867ce8f67b89288e"
05:22:47.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:47 on dist-test-slave-q99k"
05:22:47.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.919375  7660 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.005s	sys 0.004s
05:22:47.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.924790  7677 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:47.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.925984  7660 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
05:22:47.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.926285  7660 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data,/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/wal
05:22:47.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ec96d7f943684e34867ce8f67b89288e"
05:22:47.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:47 on dist-test-slave-q99k"
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.926573  7660 fs_report.cc:383] FS layout report
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/wal
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/wal
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data/data
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:47.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:47.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.966233  7660 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:47.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.971771  7660 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
05:22:47.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.972189  7660 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:47.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.974905  7660 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
05:22:47.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.979033  7660 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
05:22:47.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.979239  7660 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
05:22:47.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.979434  7660 ts_tablet_manager.cc:610] Registered 0 tablets
05:22:47.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:47.979573  7660 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
05:22:48.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.114768  7660 rpc_server.cc:307] RPC server started. Bound to: 127.7.25.67:37403
05:22:48.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.115067  7789 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.7.25.67:37403 every 8 connection(s)
05:22:48.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.117715  7660 server_base.cc:1166] Dumped server information to /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/data/info.pb
05:22:48.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.126860  7269 external_mini_cluster.cc:1388] Started /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu as pid 7660
05:22:48.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.127200  7269 external_mini_cluster.cc:1402] Reading /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/ts-2/wal/instance
05:22:48.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:48 dist-test-slave-q99k krb5kdc[7280](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488167, etypes {rep=17 tkt=17 ses=17}, kudu/127.7.25.67@KRBTEST.COM for kudu/127.7.25.126@KRBTEST.COM
05:22:48.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.154580  7792 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.122002 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.7.25.67:46119 (local address 127.7.25.126:33633)
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.126557 (+  4555us) server_negotiation.cc:206] Beginning negotiation
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.126582 (+    25us) server_negotiation.cc:399] Waiting for connection header
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.126634 (+    52us) server_negotiation.cc:407] Connection header received
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.131704 (+  5070us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.131734 (+    30us) server_negotiation.cc:461] Received NEGOTIATE request from client
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.131976 (+   242us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.132144 (+   168us) server_negotiation.cc:226] Negotiated authn=SASL
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.134875 (+  2731us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.136016 (+  1141us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.138183 (+  2167us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.138817 (+   634us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.139006 (+   189us) 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
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.143992 (+  4986us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.144016 (+    24us) server_negotiation.cc:881] Received SASL_INITIATE request from client
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.144035 (+    19us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.144131 (+    96us) server_negotiation.cc:922] calling sasl_server_start()
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.148430 (+  4299us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.149903 (+  1473us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.149929 (+    26us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.149943 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150164 (+   221us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150945 (+   781us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150964 (+    19us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150977 (+    13us) server_negotiation.cc:967] calling sasl_server_step()
05:22:48.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.151483 (+   506us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.151732 (+   249us) server_negotiation.cc:1035] Waiting for connection context
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.152799 (+  1067us) server_negotiation.cc:299] Negotiation successful
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.153080 (+   281us) negotiation.cc:327] Negotiation complete: OK
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":4120,"thread_start_us":3816,"threads_started":1}
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.155320  7793 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.122419 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.7.25.126:33633 (local address 127.7.25.67:46119)
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.125608 (+  3189us) negotiation.cc:107] Waiting for socket to connect
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.125695 (+    87us) client_negotiation.cc:174] Beginning negotiation
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.131258 (+  5563us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.132493 (+  1235us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.132527 (+    34us) client_negotiation.cc:394] Received NEGOTIATE response from server
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.133762 (+  1235us) client_negotiation.cc:189] Negotiated authn=SASL
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.134599 (+   837us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.134644 (+    45us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.136270 (+  1626us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.136292 (+    22us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.137948 (+  1656us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.137986 (+    38us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.139099 (+  1113us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
05:22:48.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.139136 (+    37us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.139473 (+   337us) 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
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.140432 (+   959us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.140495 (+    63us) client_negotiation.cc:656] calling sasl_client_start()
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.143458 (+  2963us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.148705 (+  5247us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.148728 (+    23us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.148754 (+    26us) client_negotiation.cc:760] calling sasl_client_step()
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.149432 (+   678us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150398 (+   966us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150416 (+    18us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150430 (+    14us) client_negotiation.cc:760] calling sasl_client_step()
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.150689 (+   259us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.151746 (+  1057us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.151779 (+    33us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.152195 (+   416us) client_negotiation.cc:769] Sending connection context
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.152538 (+   343us) client_negotiation.cc:240] Negotiation successful
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 05:22:48.152866 (+   328us) negotiation.cc:327] Negotiation complete: OK
05:22:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2581,"thread_start_us":1831,"threads_started":1}
05:22:48.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.157712  7790 heartbeater.cc:347] Connected to a master server at 127.7.25.126:33633
05:22:48.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.158198  7790 heartbeater.cc:464] Registering TS with master...
05:22:48.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.159305  7790 heartbeater.cc:511] Master 127.7.25.126:33633 requested a full tablet report, sending...
05:22:48.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.162113  7326 ts_manager.cc:194] Registered new tserver with Master: ec96d7f943684e34867ce8f67b89288e (127.7.25.67:37403)
05:22:48.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.163761  7326 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.7.25.67@KRBTEST.COM'} at 127.7.25.67:46119
05:22:48.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.176899  7269 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
05:22:48.184 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
05:22:48.186 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

05:22:48.195 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

05:22:48.197 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

05:22:48.206 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.7.25.126"
      port: 33633
    }
    bound_http_address {
      host: "127.7.25.126"
      port: 37943
    }
  }
}

05:22:48.210 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

05:22:48.213 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.7.25.65"
      port: 42833
    }
    bound_http_address {
      host: "127.7.25.65"
      port: 42047
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.7.25.66"
      port: 46451
    }
    bound_http_address {
      host: "127.7.25.66"
      port: 39795
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.7.25.67"
      port: 37403
    }
    bound_http_address {
      host: "127.7.25.67"
      port: 33873
    }
  }
}

05:22:48.214 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
05:22:48.468 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-task7Mr1Sa/test-tmp/mini-kudu-cluster7834404989457375247/krb5kdc/krb5cc
05:22:48.479 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
05:22:48.493 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
05:22:48.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.496011  7269 external_mini_cluster.cc:1595] Killing /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu with pid 7388
05:22:48.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.519572  7269 external_mini_cluster.cc:1595] Killing /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu with pid 7524
05:22:48.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:48.519455  7311 connection.cc:535] server connection from 127.7.25.65:47689 recv error: Network error: failed to read from TLS socket (remote: 127.7.25.65:47689): Connection reset by peer (error 104)
05:22:48.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:48.542575  7312 connection.cc:535] server connection from 127.7.25.66:42947 recv error: Network error: failed to read from TLS socket (remote: 127.7.25.66:42947): Connection reset by peer (error 104)
05:22:48.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.542936  7269 external_mini_cluster.cc:1595] Killing /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu with pid 7660
05:22:48.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:48.563486  7312 connection.cc:535] server connection from 127.7.25.67:46119 recv error: Network error: failed to read from TLS socket (remote: 127.7.25.67:46119): Connection reset by peer (error 104)
05:22:48.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:48.563858  7269 external_mini_cluster.cc:1595] Killing /tmp/dist-test-task7Mr1Sa/build/tsan/bin/kudu with pid 7293
05:22:49.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:48.592568  7278 connection.cc:535] client connection to 127.7.25.126:33633 recv error: Network error: failed to read from TLS socket (remote: 127.7.25.126:33633): Connection reset by peer (error 104)
05:22:49.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:49Z chronyd exiting

Time: 17.816

OK (1 test)

