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

19:44:06.064 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
19:44:06.065 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

19:44:06.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.069240 30698 mini_oidc.cc:152] Starting JWKS server
19:44:06.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.073046 30698 webserver.cc:460] Webserver started at http://127.0.0.1:41895/ using document root <none> and password file <none>
19:44:06.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.073695 30698 mini_oidc.cc:189] Starting OIDC Discovery server
19:44:06.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.075532 30698 webserver.cc:460] Webserver started at http://127.0.0.1:38597/ using document root <none> and password file <none>
19:44:06.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T19:44:06Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
19:44:06.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T19:44:06Z Disabled control of system clock
19:44:06.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.132836 30698 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:06.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:06.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/wal
19:44:06.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/data
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/logs
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/data/info.pb
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
19:44:06.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.29.250.190:46625
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.29.250.190
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.29.250.148:35227
19:44:06.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.29.250.190:46625
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41895/jwks.json
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
19:44:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
19:44:06.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.470827 30714 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
19:44:06.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.471431 30714 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
19:44:06.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.472139 30714 flags.cc:425] Enabled unsafe flag: --never_fsync=true
19:44:06.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.504741 30714 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
19:44:06.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.505039 30714 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
19:44:06.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.505234 30714 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
19:44:06.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.505498 30714 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
19:44:06.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.505842 30714 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
19:44:06.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.506079 30714 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:41895/jwks.json
19:44:06.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.544763 30714 master_runner.cc:386] Master server non-default flags:
19:44:06.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.29.250.148:35227
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/data
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/wal
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.29.250.190:46625
19:44:06.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.29.250.190:46625
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41895/jwks.json
19:44:06.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/data/info.pb
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.29.250.190
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
19:44:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/logs
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 18:54:03 UTC on ccycloud.quasar-qqxtnc.root.comops.site
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581135
19:44:06.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
19:44:06.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.546255 30714 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
19:44:06.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.548416 30714 file_cache.cc:493] Constructed file cache file cache with capacity 419430
19:44:06.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.569005 30724 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
19:44:07.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.569147 30721 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
19:44:07.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:06.569768 30720 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
19:44:07.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:06.571561 30714 server_base.cc:1034] running on GCE node
19:44:07.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.850289 30714 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
19:44:07.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:07.850600 30714 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
19:44:07.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.850829 30714 hybrid_clock.cc:648] HybridClock initialized: now 1786477447850797 us; error 0 us; skew 500 ppm
19:44:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.851878 30714 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
19:44:07.864 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.863663 30714 webserver.cc:460] Webserver started at http://127.29.250.190:39673/ using document root <none> and password file <none>
19:44:07.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.864990 30714 fs_manager.cc:359] Metadata directory not provided
19:44:07.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.865298 30714 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
19:44:07.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.865978 30714 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
19:44:07.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.872119 30714 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/data/instance:
19:44:07.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d803e3401fa94f4d8f6a195c449b47f1"
19:44:07.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:07 on dist-test-slave-ss4n"
19:44:07.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.873939 30714 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/wal/instance:
19:44:07.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d803e3401fa94f4d8f6a195c449b47f1"
19:44:07.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:07 on dist-test-slave-ss4n"
19:44:07.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.883000 30714 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.007s	sys 0.001s
19:44:07.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.890223 30732 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
19:44:07.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.891708 30714 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.003s
19:44:07.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.892132 30714 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/data,/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/wal
19:44:07.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d803e3401fa94f4d8f6a195c449b47f1"
19:44:07.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:07 on dist-test-slave-ss4n"
19:44:07.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:07.892659 30714 fs_report.cc:383] FS layout report
19:44:07.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
19:44:07.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/wal
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/wal
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster9011522668569464752/master-0/data/data
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
19:44:07.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
19:44:07.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
19:44:07.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
19:44:07.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
19:44:07.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
19:44:07.976 [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
19:44:09.017 [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-taskFlZeFS/build/tsan/bin/kudu: process exited with non-zero status 1"
}

19:44:10.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T19:44:10Z chronyd exiting
19:44:11.284 [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-taskFlZeFS/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]
19:44:11.294 [INFO - main] (RandomUtils.java:49) Using random seed: 1786477451294
19:44:11.304 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
19:44:11.354 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
19:44:11.355 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskFlZeFS/test-tmp
19:44:11.355 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
19:44:11.356 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskFlZeFS/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
19:44:11.358 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619"
  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 {
  }
}

19:44:11.697 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
19:44:11.698 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

19:44:11.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:11.701421 30735 mini_oidc.cc:152] Starting JWKS server
19:44:11.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:11.705211 30735 webserver.cc:460] Webserver started at http://127.0.0.1:41587/ using document root <none> and password file <none>
19:44:11.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:11.705752 30735 mini_oidc.cc:189] Starting OIDC Discovery server
19:44:11.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:11.707531 30735 webserver.cc:460] Webserver started at http://127.0.0.1:38149/ using document root <none> and password file <none>
19:44:11.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
19:44:11.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/principal' for realm 'KRBTEST.COM',
19:44:11.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
19:44:11.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:11 dist-test-slave-ss4n krb5kdc[30747](info): setting up network...
19:44:11.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
19:44:11.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:11 dist-test-slave-ss4n krb5kdc[30747](info): set up 2 sockets
19:44:11.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:11 dist-test-slave-ss4n krb5kdc[30747](info): commencing operation
19:44:11.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
19:44:13.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.016813 30735 mini_kdc.cc:121] Time spent starting KDC: real 1.282s	user 0.011s	sys 0.014s
19:44:13.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
19:44:13.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
19:44:13.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
19:44:13.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
19:44:13.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
19:44:13.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
19:44:13.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
19:44:13.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
19:44:13.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
19:44:13.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
19:44:13.146 [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-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/test-user.keytab.
19:44:13.146 [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-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/test-user.keytab.
19:44:13.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:13 dist-test-slave-ss4n krb5kdc[30747](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477453, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
19:44:13.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
19:44:13.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T19:44:13Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
19:44:13.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T19:44:13Z Disabled control of system clock
19:44:13.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.3.254@KRBTEST.COM; defaulting to no policy
19:44:13.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:13.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.3.254@KRBTEST.COM" created.
19:44:13.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:13.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.254 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.254.keytab.
19:44:13.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.254 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.254.keytab.
19:44:13.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:13.261273 30735 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/wal
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/logs
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data/info.pb
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
19:44:13.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.254:37251
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.254
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.254.keytab
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.254
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.30.3.254:37251
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41587/jwks.json
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
19:44:13.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
19:44:13.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.582104 30761 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
19:44:13.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.582702 30761 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
19:44:13.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.583441 30761 flags.cc:425] Enabled unsafe flag: --never_fsync=true
19:44:13.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.619940 30761 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
19:44:13.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.620357 30761 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
19:44:13.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.620631 30761 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
19:44:13.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.620853 30761 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
19:44:13.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.621063 30761 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
19:44:13.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.621259 30761 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
19:44:13.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.621677 30761 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:41587/jwks.json
19:44:13.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:13.660184 30761 master_runner.cc:386] Master server non-default flags:
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/wal
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.30.3.254:37251
19:44:13.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.254.keytab
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.254
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.254:37251
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
19:44:13.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41587/jwks.json
19:44:13.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data/info.pb
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.254
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:13.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/logs
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
19:44:13.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
19:44:13.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
19:44:13.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 18:54:03 UTC on ccycloud.quasar-qqxtnc.root.comops.site
19:44:13.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581135
19:44:13.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
19:44:13.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:13.661460 30761 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
19:44:13.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:13.662671 30761 file_cache.cc:493] Constructed file cache file cache with capacity 419430
19:44:13.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.683161 30770 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
19:44:14.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.683274 30769 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
19:44:14.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:13.685155 30761 server_base.cc:1034] running on GCE node
19:44:14.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:13.683280 30772 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
19:44:14.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:14.979010 30761 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
19:44:14.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:14.982199 30761 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
19:44:14.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:14.983649 30761 hybrid_clock.cc:648] HybridClock initialized: now 1786477454983607 us; error 133 us; skew 500 ppm
19:44:14.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:14.984506 30761 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
19:44:14.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:14 dist-test-slave-ss4n krb5kdc[30747](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477454, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.3.254@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
19:44:14.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:14.997017 30761 init.cc:377] Logged in from keytab as kudu/127.30.3.254@KRBTEST.COM (short username kudu)
19:44:15.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.003537 30761 webserver.cc:460] Webserver started at http://127.30.3.254:39277/ using document root <none> and password file <none>
19:44:15.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.004683 30761 fs_manager.cc:359] Metadata directory not provided
19:44:15.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.004943 30761 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
19:44:15.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.005569 30761 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
19:44:15.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.010713 30761 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data/instance:
19:44:15.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "15139b23d1054ee7b098a349f200e49c"
19:44:15.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:15 on dist-test-slave-ss4n"
19:44:15.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.012092 30761 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/wal/instance:
19:44:15.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "15139b23d1054ee7b098a349f200e49c"
19:44:15.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:15 on dist-test-slave-ss4n"
19:44:15.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.020372 30761 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.009s	sys 0.000s
19:44:15.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.026798 30783 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
19:44:15.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.028177 30761 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.002s
19:44:15.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.028620 30761 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data,/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/wal
19:44:15.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "15139b23d1054ee7b098a349f200e49c"
19:44:15.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:15 on dist-test-slave-ss4n"
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.029064 30761 fs_report.cc:383] FS layout report
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/wal
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/wal
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data/data
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
19:44:15.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
19:44:15.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
19:44:15.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.080504 30761 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
19:44:15.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.087538 30761 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
19:44:15.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.088245 30761 kserver.cc:163] Server-wide thread pool size limit: 3276
19:44:15.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.166147 30761 rpc_server.cc:307] RPC server started. Bound to: 127.30.3.254:37251
19:44:15.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.166258 30839 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.30.3.254:37251 every 8 connection(s)
19:44:15.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.169158 30761 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/data/info.pb
19:44:15.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.172708 30735 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu as pid 30761
19:44:15.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.173343 30735 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/master-0/wal/instance
19:44:15.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.176381 30840 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
19:44:15.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.200017 30840 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c: Bootstrap starting.
19:44:15.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.206432 30840 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c: Neither blocks nor log segments found. Creating new log.
19:44:15.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.208756 30840 log.cc:824] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c: Log is configured to *not* fsync() on all Append() calls
19:44:15.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.215319 30840 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c: No bootstrap required, opened a new log
19:44:15.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:15 dist-test-slave-ss4n krb5kdc[30747](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477453, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.30.3.254@KRBTEST.COM
19:44:15.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.236301 30844 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.180908 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:46042 (local address 127.30.3.254:37251)
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.184076 (+  3168us) server_negotiation.cc:206] Beginning negotiation
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.184116 (+    40us) server_negotiation.cc:399] Waiting for connection header
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.185672 (+  1556us) server_negotiation.cc:407] Connection header received
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.198794 (+ 13122us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.198851 (+    57us) server_negotiation.cc:461] Received NEGOTIATE request from client
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.199430 (+   579us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.199965 (+   535us) server_negotiation.cc:226] Negotiated authn=SASL
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.204846 (+  4881us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:15.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.207768 (+  2922us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:15.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.211476 (+  3708us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:15.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.212697 (+  1221us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:15.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.212955 (+   258us) 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
19:44:15.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.221942 (+  8987us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
19:44:15.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.221969 (+    27us) server_negotiation.cc:881] Received SASL_INITIATE request from client
19:44:15.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.222000 (+    31us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.222159 (+   159us) server_negotiation.cc:922] calling sasl_server_start()
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.226693 (+  4534us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.228794 (+  2101us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.228821 (+    27us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.228842 (+    21us) server_negotiation.cc:967] calling sasl_server_step()
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.229157 (+   315us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.230425 (+  1268us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.230446 (+    21us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.230462 (+    16us) server_negotiation.cc:967] calling sasl_server_step()
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.231432 (+   970us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.231880 (+   448us) server_negotiation.cc:1035] Waiting for connection context
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.233449 (+  1569us) server_negotiation.cc:299] Negotiation successful
19:44:15.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:15.234014 (+   565us) negotiation.cc:327] Negotiation complete: OK
19:44:15.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":2520,"thread_start_us":1978,"threads_started":1}
19:44:15.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.240134 30840 raft_consensus.cc:348] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "15139b23d1054ee7b098a349f200e49c" member_type: VOTER last_known_addr { host: "127.30.3.254" port: 37251 } }
19:44:15.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.241168 30840 raft_consensus.cc:374] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
19:44:15.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.241532 30840 raft_consensus.cc:729] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 15139b23d1054ee7b098a349f200e49c, State: Initialized, Role: FOLLOWER
19:44:15.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.242480 30840 consensus_queue.cc:260] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [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: "15139b23d1054ee7b098a349f200e49c" member_type: VOTER last_known_addr { host: "127.30.3.254" port: 37251 } }
19:44:15.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.243196 30840 raft_consensus.cc:388] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
19:44:15.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.243585 30840 raft_consensus.cc:482] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
19:44:15.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.243981 30840 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 0 FOLLOWER]: Advancing to term 1
19:44:15.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.249258 30840 raft_consensus.cc:504] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "15139b23d1054ee7b098a349f200e49c" member_type: VOTER last_known_addr { host: "127.30.3.254" port: 37251 } }
19:44:15.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.250165 30840 leader_election.cc:302] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [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: 15139b23d1054ee7b098a349f200e49c; no voters: 
19:44:15.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.252173 30840 leader_election.cc:288] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [CANDIDATE]: Term 1 election: Requested vote from peers 
19:44:15.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.252693 30848 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 1 FOLLOWER]: Leader election won for term 1
19:44:15.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.255342 30848 raft_consensus.cc:686] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [term 1 LEADER]: Becoming Leader. State: Replica: 15139b23d1054ee7b098a349f200e49c, State: Running, Role: LEADER
19:44:15.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.256666 30848 consensus_queue.cc:237] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [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: "15139b23d1054ee7b098a349f200e49c" member_type: VOTER last_known_addr { host: "127.30.3.254" port: 37251 } }
19:44:15.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.258967 30840 sys_catalog.cc:564] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [sys.catalog]: configured and running, proceeding with master startup.
19:44:15.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.271601 30850 sys_catalog.cc:455] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "15139b23d1054ee7b098a349f200e49c" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "15139b23d1054ee7b098a349f200e49c" member_type: VOTER last_known_addr { host: "127.30.3.254" port: 37251 } } }
19:44:15.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.273874 30850 sys_catalog.cc:458] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [sys.catalog]: This master's current role is: LEADER
19:44:15.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.273217 30849 sys_catalog.cc:455] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [sys.catalog]: SysCatalogTable state changed. Reason: New leader 15139b23d1054ee7b098a349f200e49c. Latest consensus state: current_term: 1 leader_uuid: "15139b23d1054ee7b098a349f200e49c" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "15139b23d1054ee7b098a349f200e49c" member_type: VOTER last_known_addr { host: "127.30.3.254" port: 37251 } } }
19:44:15.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.275864 30849 sys_catalog.cc:458] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c [sys.catalog]: This master's current role is: LEADER
19:44:15.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.279098 30857 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
19:44:15.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.291676 30857 catalog_manager.cc:1484] Initializing Kudu cluster ID...
19:44:15.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.311408 30857 catalog_manager.cc:1347] Generated new cluster ID: 69cc71b9576245b48d1c876101717190
19:44:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.311766 30857 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
19:44:15.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.337121 30857 catalog_manager.cc:1370] Generated new certificate authority record
19:44:15.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.339825 30857 catalog_manager.cc:1504] Loading token signing keys...
19:44:15.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.362313 30857 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 15139b23d1054ee7b098a349f200e49c: Generated new TSK 0
19:44:15.364 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.363673 30857 catalog_manager.cc:1514] Initializing in-progress tserver states...
19:44:15.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.3.193@KRBTEST.COM; defaulting to no policy
19:44:15.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:15.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.3.193@KRBTEST.COM" created.
19:44:15.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:15.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.193 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.193.keytab.
19:44:15.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.193 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.193.keytab.
19:44:15.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.443703 30735 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:15.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:15.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/wal
19:44:15.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data
19:44:15.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
19:44:15.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
19:44:15.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/logs
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data/info.pb
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.193:0
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.3.193
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.193
19:44:15.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.3.254:37251
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.193.keytab
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.193
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
19:44:15.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
19:44:15.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.789633 30875 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
19:44:15.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.790253 30875 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
19:44:15.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.791257 30875 flags.cc:425] Enabled unsafe flag: --never_fsync=true
19:44:15.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.825253 30875 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
19:44:15.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.825776 30875 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
19:44:15.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.827105 30875 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.30.3.193
19:44:15.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.866325 30875 tablet_server_runner.cc:78] Tablet server non-default flags:
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/wal
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.193.keytab
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.193
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.193:0
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
19:44:15.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data/info.pb
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.193
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.3.254:37251
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.3.193
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/logs
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
19:44:15.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
19:44:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
19:44:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 18:54:03 UTC on ccycloud.quasar-qqxtnc.root.comops.site
19:44:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581135
19:44:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
19:44:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.867854 30875 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
19:44:15.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.869345 30875 file_cache.cc:493] Constructed file cache file cache with capacity 419430
19:44:15.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.892273 30886 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
19:44:17.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.898312 30888 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
19:44:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:15.893445 30883 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
19:44:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:15.894105 30875 server_base.cc:1034] running on GCE node
19:44:17.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.147425 30875 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
19:44:17.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.154170 30875 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
19:44:17.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.155742 30875 hybrid_clock.cc:648] HybridClock initialized: now 1786477457155678 us; error 72 us; skew 500 ppm
19:44:17.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.156842 30875 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
19:44:17.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:17 dist-test-slave-ss4n krb5kdc[30747](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477457, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.3.193@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
19:44:17.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.164839 30875 init.cc:377] Logged in from keytab as kudu/127.30.3.193@KRBTEST.COM (short username kudu)
19:44:17.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.172808 30875 webserver.cc:460] Webserver started at http://127.30.3.193:38563/ using document root <none> and password file <none>
19:44:17.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.174077 30875 fs_manager.cc:359] Metadata directory not provided
19:44:17.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.174351 30875 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
19:44:17.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.174916 30875 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
19:44:17.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.181813 30875 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data/instance:
19:44:17.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f3b7969d604a4b7a8da1c4b5dda891ca"
19:44:17.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:17 on dist-test-slave-ss4n"
19:44:17.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.183243 30875 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/wal/instance:
19:44:17.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f3b7969d604a4b7a8da1c4b5dda891ca"
19:44:17.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:17 on dist-test-slave-ss4n"
19:44:17.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.192093 30875 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.005s	sys 0.004s
19:44:17.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.198539 30898 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
19:44:17.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.200067 30875 fs_manager.cc:725] Time spent opening block manager: real 0.005s	user 0.002s	sys 0.002s
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.200500 30875 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data,/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/wal
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f3b7969d604a4b7a8da1c4b5dda891ca"
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:17 on dist-test-slave-ss4n"
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.201004 30875 fs_report.cc:383] FS layout report
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/wal
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/wal
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data/data
19:44:17.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
19:44:17.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
19:44:17.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.253073 30875 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
19:44:17.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.260437 30875 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
19:44:17.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.260922 30875 kserver.cc:163] Server-wide thread pool size limit: 3276
19:44:17.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.263783 30875 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
19:44:17.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.268232 30875 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
19:44:17.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.268468 30875 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
19:44:17.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.268688 30875 ts_tablet_manager.cc:610] Registered 0 tablets
19:44:17.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.268813 30875 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
19:44:17.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.434064 30875 rpc_server.cc:307] RPC server started. Bound to: 127.30.3.193:45795
19:44:17.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.434207 31033 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.30.3.193:45795 every 8 connection(s)
19:44:17.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.437189 30875 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/data/info.pb
19:44:17.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.444636 30735 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu as pid 30875
19:44:17.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.445140 30735 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-0/wal/instance
19:44:17.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:17 dist-test-slave-ss4n krb5kdc[30747](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477457, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.3.193@KRBTEST.COM for kudu/127.30.3.254@KRBTEST.COM
19:44:17.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.3.194@KRBTEST.COM; defaulting to no policy
19:44:17.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:17.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.3.194@KRBTEST.COM" created.
19:44:17.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.483429 31039 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.442367 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.30.3.254:37251 (local address 127.30.3.193:45089)
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.445876 (+  3509us) negotiation.cc:107] Waiting for socket to connect
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.445964 (+    88us) client_negotiation.cc:174] Beginning negotiation
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.451665 (+  5701us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.452861 (+  1196us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.452910 (+    49us) client_negotiation.cc:394] Received NEGOTIATE response from server
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.453936 (+  1026us) client_negotiation.cc:189] Negotiated authn=SASL
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.454901 (+   965us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.454957 (+    56us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.456667 (+  1710us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.456689 (+    22us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.459402 (+  2713us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.459457 (+    55us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.460915 (+  1458us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.460953 (+    38us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.461671 (+   718us) 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
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.462848 (+  1177us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.462921 (+    73us) client_negotiation.cc:656] calling sasl_client_start()
19:44:17.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.466271 (+  3350us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.472097 (+  5826us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.472123 (+    26us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.472149 (+    26us) client_negotiation.cc:760] calling sasl_client_step()
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.472976 (+   827us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.474254 (+  1278us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.474295 (+    41us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.474331 (+    36us) client_negotiation.cc:760] calling sasl_client_step()
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.474779 (+   448us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.479177 (+  4398us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.479234 (+    57us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.479969 (+   735us) client_negotiation.cc:769] Sending connection context
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.480581 (+   612us) client_negotiation.cc:240] Negotiation successful
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.481131 (+   550us) negotiation.cc:327] Negotiation complete: OK
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2856,"thread_start_us":1966,"threads_started":1}
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.484534 31038 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.441959 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.30.3.193:45089 (local address 127.30.3.254:37251)
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.443185 (+  1226us) server_negotiation.cc:206] Beginning negotiation
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.443202 (+    17us) server_negotiation.cc:399] Waiting for connection header
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.446181 (+  2979us) server_negotiation.cc:407] Connection header received
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.452065 (+  5884us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
19:44:17.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.452099 (+    34us) server_negotiation.cc:461] Received NEGOTIATE request from client
19:44:17.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.452288 (+   189us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
19:44:17.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.452482 (+   194us) server_negotiation.cc:226] Negotiated authn=SASL
19:44:17.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.455280 (+  2798us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:17.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.456344 (+  1064us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:17.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.459756 (+  3412us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:17.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.460498 (+   742us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:17.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.460817 (+   319us) server_negotiation.cc:662] Negotiated TLSv1.2 with cipher ECDHE-RSA-AES128-GCM-SHA256 TLSv1.2 Kx=ECDH Au=RSA Enc=AESGCM(128) Mac=AEAD
19:44:17.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.466859 (+  6042us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
19:44:17.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.466881 (+    22us) server_negotiation.cc:881] Received SASL_INITIATE request from client
19:44:17.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.466900 (+    19us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
19:44:17.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.467026 (+   126us) server_negotiation.cc:922] calling sasl_server_start()
19:44:17.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.471621 (+  4595us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:17.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.473367 (+  1746us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:17.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.473400 (+    33us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.473414 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.473834 (+   420us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.477926 (+  4092us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.477962 (+    36us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.477993 (+    31us) server_negotiation.cc:967] calling sasl_server_step()
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.478723 (+   730us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.479029 (+   306us) server_negotiation.cc:1035] Waiting for connection context
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.480972 (+  1943us) server_negotiation.cc:299] Negotiation successful
19:44:17.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:17.481631 (+   659us) negotiation.cc:327] Negotiation complete: OK
19:44:17.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":861,"thread_start_us":596,"threads_started":1}
19:44:17.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.487241 31034 heartbeater.cc:347] Connected to a master server at 127.30.3.254:37251
19:44:17.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.487825 31034 heartbeater.cc:464] Registering TS with master...
19:44:17.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.489379 31034 heartbeater.cc:511] Master 127.30.3.254:37251 requested a full tablet report, sending...
19:44:17.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.494082 30803 ts_manager.cc:194] Registered new tserver with Master: f3b7969d604a4b7a8da1c4b5dda891ca (127.30.3.193:45795)
19:44:17.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.497776 30803 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.30.3.193@KRBTEST.COM'} at 127.30.3.193:45089
19:44:17.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:17.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.194 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.194.keytab.
19:44:17.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.194 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.194.keytab.
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.511567 30735 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/wal
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/logs
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data/info.pb
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.194:0
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.3.194
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.194
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.3.254:37251
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.194.keytab
19:44:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.194
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
19:44:17.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
19:44:17.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.844786 31042 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
19:44:17.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.845396 31042 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
19:44:17.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.846244 31042 flags.cc:425] Enabled unsafe flag: --never_fsync=true
19:44:17.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.878393 31042 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
19:44:17.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.878854 31042 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
19:44:17.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.880079 31042 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.30.3.194
19:44:17.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.924213 31042 tablet_server_runner.cc:78] Tablet server non-default flags:
19:44:17.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:17.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:17.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/wal
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.194.keytab
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.194
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.194:0
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data/info.pb
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.194
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.3.254:37251
19:44:17.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.3.194
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/logs
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 18:54:03 UTC on ccycloud.quasar-qqxtnc.root.comops.site
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581135
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
19:44:17.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.925804 31042 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
19:44:17.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.927213 31042 file_cache.cc:493] Constructed file cache file cache with capacity 419430
19:44:17.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.950126 31052 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
19:44:18.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:18.502928 31034 heartbeater.cc:503] Master 127.30.3.254:37251 was elected leader, sending a full tablet report...
19:44:19.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.952291 31054 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
19:44:19.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:17.950917 31050 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
19:44:19.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:17.955140 31042 server_base.cc:1034] running on GCE node
19:44:19.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.205770 31042 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
19:44:19.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.208796 31042 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
19:44:19.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.210287 31042 hybrid_clock.cc:648] HybridClock initialized: now 1786477459210217 us; error 67 us; skew 500 ppm
19:44:19.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.211344 31042 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
19:44:19.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:19 dist-test-slave-ss4n krb5kdc[30747](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477459, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.3.194@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
19:44:19.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.218117 31042 init.cc:377] Logged in from keytab as kudu/127.30.3.194@KRBTEST.COM (short username kudu)
19:44:19.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.224191 31042 webserver.cc:460] Webserver started at http://127.30.3.194:35029/ using document root <none> and password file <none>
19:44:19.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.225293 31042 fs_manager.cc:359] Metadata directory not provided
19:44:19.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.225601 31042 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
19:44:19.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.226177 31042 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
19:44:19.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.231405 31042 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data/instance:
19:44:19.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "74f27d9b2a34419db005056adfcb97a5"
19:44:19.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:19 on dist-test-slave-ss4n"
19:44:19.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.232733 31042 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/wal/instance:
19:44:19.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "74f27d9b2a34419db005056adfcb97a5"
19:44:19.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:19 on dist-test-slave-ss4n"
19:44:19.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.240963 31042 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.005s	sys 0.001s
19:44:19.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.247467 31063 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
19:44:19.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.248871 31042 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.000s
19:44:19.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.249286 31042 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data,/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/wal
19:44:19.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "74f27d9b2a34419db005056adfcb97a5"
19:44:19.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:19 on dist-test-slave-ss4n"
19:44:19.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.249768 31042 fs_report.cc:383] FS layout report
19:44:19.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
19:44:19.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/wal
19:44:19.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/wal
19:44:19.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data/data
19:44:19.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
19:44:19.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
19:44:19.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.291672 31042 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
19:44:19.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.298302 31042 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
19:44:19.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.298877 31042 kserver.cc:163] Server-wide thread pool size limit: 3276
19:44:19.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.302042 31042 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
19:44:19.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.306514 31042 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
19:44:19.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.306785 31042 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
19:44:19.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.307076 31042 ts_tablet_manager.cc:610] Registered 0 tablets
19:44:19.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.307327 31042 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
19:44:19.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.469519 31042 rpc_server.cc:307] RPC server started. Bound to: 127.30.3.194:39903
19:44:19.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.469760 31193 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.30.3.194:39903 every 8 connection(s)
19:44:19.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.472836 31042 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/data/info.pb
19:44:19.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.474675 30735 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu as pid 31042
19:44:19.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.475297 30735 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-1/wal/instance
19:44:19.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.3.195@KRBTEST.COM; defaulting to no policy
19:44:19.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:19.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.3.195@KRBTEST.COM" created.
19:44:19.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:19 dist-test-slave-ss4n krb5kdc[30747](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477459, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.3.194@KRBTEST.COM for kudu/127.30.3.254@KRBTEST.COM
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.528880 31200 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.479206 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.30.3.254:37251 (local address 127.30.3.194:33989)
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.491772 (+ 12566us) negotiation.cc:107] Waiting for socket to connect
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.491890 (+   118us) client_negotiation.cc:174] Beginning negotiation
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.497404 (+  5514us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.498829 (+  1425us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.498873 (+    44us) client_negotiation.cc:394] Received NEGOTIATE response from server
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.499829 (+   956us) client_negotiation.cc:189] Negotiated authn=SASL
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.500886 (+  1057us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.500963 (+    77us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.503444 (+  2481us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.503483 (+    39us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
19:44:19.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.505658 (+  2175us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.505731 (+    73us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.507315 (+  1584us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.507342 (+    27us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.507751 (+   409us) 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
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.508953 (+  1202us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.509062 (+   109us) client_negotiation.cc:656] calling sasl_client_start()
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.513956 (+  4894us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.520516 (+  6560us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.520548 (+    32us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.520577 (+    29us) client_negotiation.cc:760] calling sasl_client_step()
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.521697 (+  1120us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.522956 (+  1259us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.523035 (+    79us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.523082 (+    47us) client_negotiation.cc:760] calling sasl_client_step()
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.523512 (+   430us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.525155 (+  1643us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.525188 (+    33us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.525721 (+   533us) client_negotiation.cc:769] Sending connection context
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.526254 (+   533us) client_negotiation.cc:240] Negotiation successful
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.526666 (+   412us) negotiation.cc:327] Negotiation complete: OK
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":11838,"thread_start_us":10777,"threads_started":1}
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.529718 31197 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.478497 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.30.3.194:33989 (local address 127.30.3.254:37251)
19:44:19.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.492804 (+ 14307us) server_negotiation.cc:206] Beginning negotiation
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.492832 (+    28us) server_negotiation.cc:399] Waiting for connection header
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.492899 (+    67us) server_negotiation.cc:407] Connection header received
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.497982 (+  5083us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.498015 (+    33us) server_negotiation.cc:461] Received NEGOTIATE request from client
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.498233 (+   218us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.498454 (+   221us) server_negotiation.cc:226] Negotiated authn=SASL
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.501368 (+  2914us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.502981 (+  1613us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.506071 (+  3090us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.506906 (+   835us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:19.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.507136 (+   230us) 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
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.514517 (+  7381us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.514544 (+    27us) server_negotiation.cc:881] Received SASL_INITIATE request from client
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.514564 (+    20us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.514674 (+   110us) server_negotiation.cc:922] calling sasl_server_start()
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.519980 (+  5306us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.522184 (+  2204us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.522223 (+    39us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.522250 (+    27us) server_negotiation.cc:967] calling sasl_server_step()
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.522554 (+   304us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.523901 (+  1347us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.523938 (+    37us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.523968 (+    30us) server_negotiation.cc:967] calling sasl_server_step()
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.524769 (+   801us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.525129 (+   360us) server_negotiation.cc:1035] Waiting for connection context
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.526633 (+  1504us) server_negotiation.cc:299] Negotiation successful
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:19.527141 (+   508us) negotiation.cc:327] Negotiation complete: OK
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":13794,"thread_start_us":13445,"threads_started":1}
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.532168 31194 heartbeater.cc:347] Connected to a master server at 127.30.3.254:37251
19:44:19.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.532549 31194 heartbeater.cc:464] Registering TS with master...
19:44:19.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.533836 31194 heartbeater.cc:511] Master 127.30.3.254:37251 requested a full tablet report, sending...
19:44:19.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.536731 30803 ts_manager.cc:194] Registered new tserver with Master: 74f27d9b2a34419db005056adfcb97a5 (127.30.3.194:39903)
19:44:19.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.538892 30803 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.30.3.194@KRBTEST.COM'} at 127.30.3.194:33989
19:44:19.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
19:44:19.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.195 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.195.keytab.
19:44:19.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.3.195 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.195.keytab.
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.543787 30735 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/wal
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/logs
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data/info.pb
19:44:19.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.195:0
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.3.195
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.195
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.3.254:37251
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.195.keytab
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.195
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
19:44:19.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
19:44:19.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.879237 31203 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
19:44:19.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.879748 31203 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
19:44:19.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.880338 31203 flags.cc:425] Enabled unsafe flag: --never_fsync=true
19:44:19.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.912227 31203 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
19:44:19.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.912598 31203 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
19:44:19.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.914011 31203 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.30.3.195
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.955156 31203 tablet_server_runner.cc:78] Tablet server non-default flags:
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.3.212:35275
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/wal
19:44:19.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kudu_127.30.3.195.keytab
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.3.195
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.3.195:0
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data/info.pb
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.3.195
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.3.254:37251
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.3.195
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/logs
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
19:44:19.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 11 Aug 2026 18:54:03 UTC on ccycloud.quasar-qqxtnc.root.comops.site
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581135
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.956321 31203 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
19:44:19.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:19.957576 31203 file_cache.cc:493] Constructed file cache file cache with capacity 419430
19:44:19.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.979689 31209 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
19:44:20.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:20.543859 31194 heartbeater.cc:503] Master 127.30.3.254:37251 was elected leader, sending a full tablet report...
19:44:21.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.979679 31213 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
19:44:21.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:19.981077 31210 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
19:44:21.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:21.284107 31211 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
19:44:21.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.284257 31203 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
19:44:21.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.288705 31203 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
19:44:21.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.291829 31203 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
19:44:21.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.293419 31203 hybrid_clock.cc:648] HybridClock initialized: now 1786477461293309 us; error 115 us; skew 500 ppm
19:44:21.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.294518 31203 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
19:44:21.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:21 dist-test-slave-ss4n krb5kdc[30747](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477461, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.3.195@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
19:44:21.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.306936 31203 init.cc:377] Logged in from keytab as kudu/127.30.3.195@KRBTEST.COM (short username kudu)
19:44:21.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.312940 31203 webserver.cc:460] Webserver started at http://127.30.3.195:35439/ using document root <none> and password file <none>
19:44:21.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.314004 31203 fs_manager.cc:359] Metadata directory not provided
19:44:21.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.314284 31203 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
19:44:21.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.314889 31203 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
19:44:21.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.320128 31203 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data/instance:
19:44:21.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "746b916599d84adfbe9ab37bffbcdcdb"
19:44:21.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:21 on dist-test-slave-ss4n"
19:44:21.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.321461 31203 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/wal/instance:
19:44:21.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "746b916599d84adfbe9ab37bffbcdcdb"
19:44:21.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:21 on dist-test-slave-ss4n"
19:44:21.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.330382 31203 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.004s	sys 0.003s
19:44:21.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.336668 31221 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
19:44:21.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.338050 31203 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.001s
19:44:21.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.338454 31203 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data,/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/wal
19:44:21.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "746b916599d84adfbe9ab37bffbcdcdb"
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 19:44:21 on dist-test-slave-ss4n"
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.338881 31203 fs_report.cc:383] FS layout report
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/wal
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/wal
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data/data
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
19:44:21.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
19:44:21.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
19:44:21.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
19:44:21.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
19:44:21.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
19:44:21.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
19:44:21.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.386329 31203 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
19:44:21.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.393563 31203 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
19:44:21.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.394150 31203 kserver.cc:163] Server-wide thread pool size limit: 3276
19:44:21.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.397042 31203 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
19:44:21.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.401367 31203 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
19:44:21.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.401657 31203 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.001s	sys 0.000s
19:44:21.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.401965 31203 ts_tablet_manager.cc:610] Registered 0 tablets
19:44:21.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.402235 31203 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
19:44:21.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.570084 31203 rpc_server.cc:307] RPC server started. Bound to: 127.30.3.195:43611
19:44:21.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.570194 31346 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.30.3.195:43611 every 8 connection(s)
19:44:21.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.573073 31203 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/data/info.pb
19:44:21.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.576731 30735 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu as pid 31203
19:44:21.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.577420 30735 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/ts-2/wal/instance
19:44:21.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 19:44:21 dist-test-slave-ss4n krb5kdc[30747](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786477461, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.3.195@KRBTEST.COM for kudu/127.30.3.254@KRBTEST.COM
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.619218 31351 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.579027 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.30.3.254:37251 (local address 127.30.3.195:58743)
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.582980 (+  3953us) negotiation.cc:107] Waiting for socket to connect
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.583064 (+    84us) client_negotiation.cc:174] Beginning negotiation
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.588601 (+  5537us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.590112 (+  1511us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.590167 (+    55us) client_negotiation.cc:394] Received NEGOTIATE response from server
19:44:21.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.591682 (+  1515us) client_negotiation.cc:189] Negotiated authn=SASL
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.592880 (+  1198us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.592959 (+    79us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.595130 (+  2171us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.595154 (+    24us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.597398 (+  2244us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.597445 (+    47us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.599336 (+  1891us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.599375 (+    39us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.599893 (+   518us) 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
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.601350 (+  1457us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.601432 (+    82us) client_negotiation.cc:656] calling sasl_client_start()
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.605208 (+  3776us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.611144 (+  5936us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.611174 (+    30us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.611203 (+    29us) client_negotiation.cc:760] calling sasl_client_step()
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.612106 (+   903us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.613490 (+  1384us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.613537 (+    47us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
19:44:21.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.613559 (+    22us) client_negotiation.cc:760] calling sasl_client_step()
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.614026 (+   467us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.615606 (+  1580us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.615650 (+    44us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.616245 (+   595us) client_negotiation.cc:769] Sending connection context
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.616761 (+   516us) client_negotiation.cc:240] Negotiation successful
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.617145 (+   384us) negotiation.cc:327] Negotiation complete: OK
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":3305,"thread_start_us":2267,"threads_started":1}
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.619316 31350 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.578762 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.30.3.195:58743 (local address 127.30.3.254:37251)
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.580299 (+  1537us) server_negotiation.cc:206] Beginning negotiation
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.580327 (+    28us) server_negotiation.cc:399] Waiting for connection header
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.583302 (+  2975us) server_negotiation.cc:407] Connection header received
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.589063 (+  5761us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.589087 (+    24us) server_negotiation.cc:461] Received NEGOTIATE request from client
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.589290 (+   203us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.589724 (+   434us) server_negotiation.cc:226] Negotiated authn=SASL
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.593290 (+  3566us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.594770 (+  1480us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.597772 (+  3002us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.598881 (+  1109us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.599302 (+   421us) 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
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.605848 (+  6546us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.605871 (+    23us) server_negotiation.cc:881] Received SASL_INITIATE request from client
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.605891 (+    20us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.606001 (+   110us) server_negotiation.cc:922] calling sasl_server_start()
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.610672 (+  4671us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:21.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.612651 (+  1979us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.612675 (+    24us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.612693 (+    18us) server_negotiation.cc:967] calling sasl_server_step()
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.613049 (+   356us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.614375 (+  1326us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.614396 (+    21us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.614410 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.615231 (+   821us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.615566 (+   335us) server_negotiation.cc:1035] Waiting for connection context
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.617125 (+  1559us) server_negotiation.cc:299] Negotiation successful
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0811 19:44:21.617472 (+   347us) negotiation.cc:327] Negotiation complete: OK
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"mutex_wait_us":114,"server-negotiator.queue_time_us":1026,"thread_start_us":668,"threads_started":1}
19:44:21.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.622370 31347 heartbeater.cc:347] Connected to a master server at 127.30.3.254:37251
19:44:21.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.622843 31347 heartbeater.cc:464] Registering TS with master...
19:44:21.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.624150 31347 heartbeater.cc:511] Master 127.30.3.254:37251 requested a full tablet report, sending...
19:44:21.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.626837 30803 ts_manager.cc:194] Registered new tserver with Master: 746b916599d84adfbe9ab37bffbcdcdb (127.30.3.195:43611)
19:44:21.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.628901 30803 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.30.3.195@KRBTEST.COM'} at 127.30.3.195:58743
19:44:21.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.631734 30735 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
19:44:21.640 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
19:44:21.641 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

19:44:21.655 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

19:44:21.657 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

19:44:21.667 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.30.3.254"
      port: 37251
    }
    bound_http_address {
      host: "127.30.3.254"
      port: 39277
    }
  }
}

19:44:21.671 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

19:44:21.674 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.30.3.193"
      port: 45795
    }
    bound_http_address {
      host: "127.30.3.193"
      port: 38563
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.30.3.194"
      port: 39903
    }
    bound_http_address {
      host: "127.30.3.194"
      port: 35029
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.30.3.195"
      port: 43611
    }
    bound_http_address {
      host: "127.30.3.195"
      port: 35439
    }
  }
}

19:44:21.674 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
19:44:21.897 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskFlZeFS/test-tmp/mini-kudu-cluster2892657439790511619/krb5kdc/krb5cc
19:44:21.910 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
19:44:21.929 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
19:44:21.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.933272 30735 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu with pid 30875
19:44:21.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:21.973734 30787 connection.cc:535] server connection from 127.30.3.193:45089 recv error: Network error: failed to read from TLS socket (remote: 127.30.3.193:45089): Connection reset by peer (error 104)
19:44:21.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:21.974248 30735 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu with pid 31042
19:44:22.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:22.009563 30787 connection.cc:535] server connection from 127.30.3.194:33989 recv error: Network error: failed to read from TLS socket (remote: 127.30.3.194:33989): Connection reset by peer (error 104)
19:44:22.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:22.020737 30735 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu with pid 31203
19:44:22.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:22.055662 30786 connection.cc:535] server connection from 127.30.3.195:58743 recv error: Network error: failed to read from TLS socket (remote: 127.30.3.195:58743): Connection reset by peer (error 104)
19:44:22.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 19:44:22.056309 30735 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskFlZeFS/build/tsan/bin/kudu with pid 30761
19:44:23.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 19:44:22.095887 30745 connection.cc:535] client connection to 127.30.3.254:37251 recv error: Network error: failed to read from TLS socket (remote: 127.30.3.254:37251): Connection reset by peer (error 104)
19:44:23.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T19:44:23Z chronyd exiting

Time: 19.277

OK (1 test)

