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

08:06:57.779 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
08:06:57.781 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

08:06:57.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:57.785717 27645 mini_oidc.cc:152] Starting JWKS server
08:06:57.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:57.789438 27645 webserver.cc:460] Webserver started at http://127.0.0.1:44407/ using document root <none> and password file <none>
08:06:57.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:57.790021 27645 mini_oidc.cc:189] Starting OIDC Discovery server
08:06:57.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:57.791942 27645 webserver.cc:460] Webserver started at http://127.0.0.1:37015/ using document root <none> and password file <none>
08:06:57.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T08:06:57Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
08:06:57.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T08:06:57Z Disabled control of system clock
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:57.846413 27645 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/wal
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/data
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:06:57.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/logs
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/data/info.pb
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:06:57.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.26.255.126:39553
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.26.255.126
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:06:57.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.26.255.84:40789
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.26.255.126:39553
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44407/jwks.json
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
08:06:57.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
08:06:58.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.163246 27668 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
08:06:58.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.163925 27668 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:06:58.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.164678 27668 flags.cc:425] Enabled unsafe flag: --never_fsync=true
08:06:58.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.198534 27668 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
08:06:58.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.198927 27668 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
08:06:58.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.199256 27668 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
08:06:58.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.199625 27668 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
08:06:58.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.200030 27668 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
08:06:58.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.200275 27668 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:44407/jwks.json
08:06:58.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:58.237859 27668 master_runner.cc:386] Master server non-default flags:
08:06:58.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.26.255.84:40789
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/data
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/wal
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.26.255.126:39553
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:06:58.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:06:58.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:06:58.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.26.255.126:39553
08:06:58.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:06:58.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:06:58.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:06:58.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44407/jwks.json
08:06:58.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/data/info.pb
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.26.255.126
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:06:58.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/logs
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:06:58.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 06:37:03 UTC on ccycloud.quasar-wifqrz.root.comops.site
08:06:58.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581996
08:06:58.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:06:58.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:58.239622 27668 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:06:58.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:58.242298 27668 file_cache.cc:493] Constructed file cache file cache with capacity 419430
08:06:58.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.261626 27680 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
08:06:59.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.261718 27685 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
08:06:59.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:58.261763 27682 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
08:06:59.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:59.386626 27683 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
08:06:59.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.386804 27668 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
08:06:59.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.391532 27668 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
08:06:59.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:06:59.391927 27668 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
08:06:59.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.392203 27668 hybrid_clock.cc:648] HybridClock initialized: now 1786522019392168 us; error 0 us; skew 500 ppm
08:06:59.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.393394 27668 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:06:59.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.405047 27668 webserver.cc:460] Webserver started at http://127.26.255.126:34763/ using document root <none> and password file <none>
08:06:59.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.406323 27668 fs_manager.cc:359] Metadata directory not provided
08:06:59.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.406589 27668 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:06:59.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.407244 27668 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
08:06:59.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.413261 27668 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/data/instance:
08:06:59.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "acd2bf06cb66473cb321ccdad36cf77a"
08:06:59.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:06:59 on dist-test-slave-6bbx"
08:06:59.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.414867 27668 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/wal/instance:
08:06:59.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "acd2bf06cb66473cb321ccdad36cf77a"
08:06:59.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:06:59 on dist-test-slave-6bbx"
08:06:59.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.423628 27668 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.006s	sys 0.001s
08:06:59.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.430315 27699 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:06:59.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.431763 27668 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.001s	sys 0.006s
08:06:59.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.432165 27668 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/data,/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/wal
08:06:59.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "acd2bf06cb66473cb321ccdad36cf77a"
08:06:59.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:06:59 on dist-test-slave-6bbx"
08:06:59.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:06:59.432616 27668 fs_report.cc:383] FS layout report
08:06:59.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:06:59.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/wal
08:06:59.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/wal
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster6152479117770181739/master-0/data/data
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:06:59.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:06:59.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:06:59.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:06:59.506 [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
08:07:00.551 [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-taskKyCRH_/build/tsan/bin/kudu: process exited with non-zero status 1"
}

08:07:01.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T08:07:01Z chronyd exiting
08:07:02.688 [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-taskKyCRH_/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]
08:07:02.698 [INFO - main] (RandomUtils.java:49) Using random seed: 1786522022697
08:07:02.708 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
08:07:02.758 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
08:07:02.759 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskKyCRH_/test-tmp
08:07:02.759 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
08:07:02.760 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskKyCRH_/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
08:07:02.762 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218"
  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 {
  }
}

08:07:03.097 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
08:07:03.097 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

08:07:03.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:03.100950 27704 mini_oidc.cc:152] Starting JWKS server
08:07:03.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:03.104764 27704 webserver.cc:460] Webserver started at http://127.0.0.1:41827/ using document root <none> and password file <none>
08:07:03.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:03.105290 27704 mini_oidc.cc:189] Starting OIDC Discovery server
08:07:03.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:03.107160 27704 webserver.cc:460] Webserver started at http://127.0.0.1:33865/ using document root <none> and password file <none>
08:07:03.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
08:07:03.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/principal' for realm 'KRBTEST.COM',
08:07:03.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
08:07:03.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:03 dist-test-slave-6bbx krb5kdc[27721](info): setting up network...
08:07:03.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
08:07:03.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:03 dist-test-slave-6bbx krb5kdc[27721](info): set up 2 sockets
08:07:03.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:03 dist-test-slave-6bbx krb5kdc[27721](info): commencing operation
08:07:03.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
08:07:04.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.387696 27704 mini_kdc.cc:121] Time spent starting KDC: real 1.254s	user 0.000s	sys 0.029s
08:07:04.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
08:07:04.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:07:04.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
08:07:04.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
08:07:04.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:07:04.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
08:07:04.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
08:07:04.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:07:04.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
08:07:04.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:07:04.500 [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-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/test-user.keytab.
08:07:04.500 [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-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/test-user.keytab.
08:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:04 dist-test-slave-6bbx krb5kdc[27721](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522024, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
08:07:04.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T08:07:04Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
08:07:04.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T08:07:04Z Disabled control of system clock
08:07:04.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.14.62@KRBTEST.COM; defaulting to no policy
08:07:04.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:04.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.14.62@KRBTEST.COM" created.
08:07:04.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:04.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.62.keytab.
08:07:04.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.62.keytab.
08:07:04.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:04.613253 27704 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/wal
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:04.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/logs
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data/info.pb
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.62:36401
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.62
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.62.keytab
08:07:04.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.62
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.27.14.62:36401
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41827/jwks.json
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
08:07:04.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:07:04.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.932432 27741 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
08:07:04.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.933174 27741 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:07:04.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.934052 27741 flags.cc:425] Enabled unsafe flag: --never_fsync=true
08:07:04.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.966686 27741 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
08:07:04.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.967150 27741 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
08:07:04.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.967607 27741 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
08:07:04.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.967970 27741 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
08:07:04.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.968354 27741 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
08:07:04.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.968688 27741 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
08:07:04.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:04.968950 27741 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:41827/jwks.json
08:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:05.006803 27741 master_runner.cc:386] Master server non-default flags:
08:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data
08:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/wal
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.27.14.62:36401
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.62.keytab
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.62
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.62:36401
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:07:05.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:07:05.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:07:05.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41827/jwks.json
08:07:05.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:05.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
08:07:05.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:07:05.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:07:05.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:05.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:05.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data/info.pb
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.62
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/logs
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:07:05.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 06:37:03 UTC on ccycloud.quasar-wifqrz.root.comops.site
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581996
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:05.008157 27741 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:07:05.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:05.009369 27741 file_cache.cc:493] Constructed file cache file cache with capacity 419430
08:07:05.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:05.028576 27753 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
08:07:06.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:05.029223 27757 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
08:07:06.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:05.031163 27741 server_base.cc:1034] running on GCE node
08:07:06.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:05.028609 27755 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
08:07:06.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.155565 27741 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:07:06.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.158735 27741 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:07:06.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.160184 27741 hybrid_clock.cc:648] HybridClock initialized: now 1786522026160088 us; error 85 us; skew 500 ppm
08:07:06.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.161230 27741 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:07:06.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:06 dist-test-slave-6bbx krb5kdc[27721](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522026, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.14.62@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:07:06.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.172854 27741 init.cc:377] Logged in from keytab as kudu/127.27.14.62@KRBTEST.COM (short username kudu)
08:07:06.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.179412 27741 webserver.cc:460] Webserver started at http://127.27.14.62:41413/ using document root <none> and password file <none>
08:07:06.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.180991 27741 fs_manager.cc:359] Metadata directory not provided
08:07:06.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.181294 27741 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:07:06.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.182005 27741 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
08:07:06.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.187945 27741 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data/instance:
08:07:06.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "6679fc145cee4ca286f8a8b70f360c79"
08:07:06.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:06 on dist-test-slave-6bbx"
08:07:06.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.189579 27741 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/wal/instance:
08:07:06.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "6679fc145cee4ca286f8a8b70f360c79"
08:07:06.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:06 on dist-test-slave-6bbx"
08:07:06.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.198545 27741 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.005s	sys 0.004s
08:07:06.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.205681 27773 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:07:06.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.207376 27741 fs_manager.cc:725] Time spent opening block manager: real 0.005s	user 0.005s	sys 0.000s
08:07:06.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.207864 27741 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data,/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/wal
08:07:06.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "6679fc145cee4ca286f8a8b70f360c79"
08:07:06.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:06 on dist-test-slave-6bbx"
08:07:06.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.208367 27741 fs_report.cc:383] FS layout report
08:07:06.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:07:06.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/wal
08:07:06.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/wal
08:07:06.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data/data
08:07:06.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:07:06.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:07:06.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:07:06.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:07:06.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.266690 27741 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
08:07:06.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.273401 27741 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
08:07:06.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.273970 27741 kserver.cc:163] Server-wide thread pool size limit: 3276
08:07:06.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.349094 27741 rpc_server.cc:307] RPC server started. Bound to: 127.27.14.62:36401
08:07:06.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.349195 27861 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.14.62:36401 every 8 connection(s)
08:07:06.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.352257 27741 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/data/info.pb
08:07:06.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.354451 27704 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu as pid 27741
08:07:06.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.355108 27704 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/master-0/wal/instance
08:07:06.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.358500 27865 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
08:07:06.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.383455 27865 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79: Bootstrap starting.
08:07:06.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.390467 27865 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79: Neither blocks nor log segments found. Creating new log.
08:07:06.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.393512 27865 log.cc:824] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79: Log is configured to *not* fsync() on all Append() calls
08:07:06.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:06 dist-test-slave-6bbx krb5kdc[27721](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522024, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.27.14.62@KRBTEST.COM
08:07:06.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.401240 27865 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79: No bootstrap required, opened a new log
08:07:06.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.423435 27868 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.361080 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:32936 (local address 127.27.14.62:36401)
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.363985 (+  2905us) server_negotiation.cc:206] Beginning negotiation
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.364028 (+    43us) server_negotiation.cc:399] Waiting for connection header
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.365515 (+  1487us) server_negotiation.cc:407] Connection header received
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.375762 (+ 10247us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.375839 (+    77us) server_negotiation.cc:461] Received NEGOTIATE request from client
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.376429 (+   590us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.376980 (+   551us) server_negotiation.cc:226] Negotiated authn=SASL
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.381346 (+  4366us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.384104 (+  2758us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.386716 (+  2612us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.388344 (+  1628us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.388760 (+   416us) 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
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.402548 (+ 13788us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.402590 (+    42us) server_negotiation.cc:881] Received SASL_INITIATE request from client
08:07:06.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.402635 (+    45us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.402825 (+   190us) server_negotiation.cc:922] calling sasl_server_start()
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.410407 (+  7582us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.413326 (+  2919us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.413364 (+    38us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.413391 (+    27us) server_negotiation.cc:967] calling sasl_server_step()
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.413854 (+   463us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.415375 (+  1521us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.415407 (+    32us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.415431 (+    24us) server_negotiation.cc:967] calling sasl_server_step()
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.416987 (+  1556us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.417605 (+   618us) server_negotiation.cc:1035] Waiting for connection context
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.419574 (+  1969us) server_negotiation.cc:299] Negotiation successful
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:06.420221 (+   647us) negotiation.cc:327] Negotiation complete: OK
08:07:06.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":2281,"thread_start_us":1793,"threads_started":1}
08:07:06.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.434152 27865 raft_consensus.cc:348] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6679fc145cee4ca286f8a8b70f360c79" member_type: VOTER last_known_addr { host: "127.27.14.62" port: 36401 } }
08:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.435834 27865 raft_consensus.cc:374] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.436527 27865 raft_consensus.cc:729] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 6679fc145cee4ca286f8a8b70f360c79, State: Initialized, Role: FOLLOWER
08:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.437800 27865 consensus_queue.cc:260] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [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: "6679fc145cee4ca286f8a8b70f360c79" member_type: VOTER last_known_addr { host: "127.27.14.62" port: 36401 } }
08:07:06.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.438867 27865 raft_consensus.cc:388] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
08:07:06.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.439514 27865 raft_consensus.cc:482] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
08:07:06.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.440274 27865 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 0 FOLLOWER]: Advancing to term 1
08:07:06.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.447191 27865 raft_consensus.cc:504] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6679fc145cee4ca286f8a8b70f360c79" member_type: VOTER last_known_addr { host: "127.27.14.62" port: 36401 } }
08:07:06.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.448798 27865 leader_election.cc:302] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [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: 6679fc145cee4ca286f8a8b70f360c79; no voters: 
08:07:06.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.451067 27865 leader_election.cc:288] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [CANDIDATE]: Term 1 election: Requested vote from peers 
08:07:06.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.451510 27872 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 1 FOLLOWER]: Leader election won for term 1
08:07:06.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.456426 27865 sys_catalog.cc:564] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [sys.catalog]: configured and running, proceeding with master startup.
08:07:06.460 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.459628 27872 raft_consensus.cc:686] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [term 1 LEADER]: Becoming Leader. State: Replica: 6679fc145cee4ca286f8a8b70f360c79, State: Running, Role: LEADER
08:07:06.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.460700 27872 consensus_queue.cc:237] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [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: "6679fc145cee4ca286f8a8b70f360c79" member_type: VOTER last_known_addr { host: "127.27.14.62" port: 36401 } }
08:07:06.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.475107 27874 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 6679fc145cee4ca286f8a8b70f360c79. Latest consensus state: current_term: 1 leader_uuid: "6679fc145cee4ca286f8a8b70f360c79" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6679fc145cee4ca286f8a8b70f360c79" member_type: VOTER last_known_addr { host: "127.27.14.62" port: 36401 } } }
08:07:06.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.476069 27874 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [sys.catalog]: This master's current role is: LEADER
08:07:06.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.478272 27873 sys_catalog.cc:455] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "6679fc145cee4ca286f8a8b70f360c79" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "6679fc145cee4ca286f8a8b70f360c79" member_type: VOTER last_known_addr { host: "127.27.14.62" port: 36401 } } }
08:07:06.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.479490 27873 sys_catalog.cc:458] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79 [sys.catalog]: This master's current role is: LEADER
08:07:06.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.486011 27890 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
08:07:06.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.495270 27890 catalog_manager.cc:1484] Initializing Kudu cluster ID...
08:07:06.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.512025 27890 catalog_manager.cc:1347] Generated new cluster ID: 899f59abc1d6421dbef1373ea05dc088
08:07:06.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.512377 27890 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
08:07:06.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.533110 27890 catalog_manager.cc:1370] Generated new certificate authority record
08:07:06.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.535696 27890 catalog_manager.cc:1504] Loading token signing keys...
08:07:06.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.551375 27890 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 6679fc145cee4ca286f8a8b70f360c79: Generated new TSK 0
08:07:06.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.552731 27890 catalog_manager.cc:1514] Initializing in-progress tserver states...
08:07:06.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.14.1@KRBTEST.COM; defaulting to no policy
08:07:06.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:06.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.14.1@KRBTEST.COM" created.
08:07:06.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:06.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.1.keytab.
08:07:06.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.1.keytab.
08:07:06.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:06.621021 27704 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:06.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:06.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/wal
08:07:06.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/logs
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data/info.pb
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:06.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.1:0
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.14.1
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.1
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.14.62:36401
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.1.keytab
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.1
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:07:06.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:06.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:06.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:06.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:06.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
08:07:06.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
08:07:06.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:07:06.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:06.959759 27903 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
08:07:06.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:06.960341 27903 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:07:06.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:06.960944 27903 flags.cc:425] Enabled unsafe flag: --never_fsync=true
08:07:06.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:06.994329 27903 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
08:07:06.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:06.994685 27903 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
08:07:06.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:06.995620 27903 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.14.1
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:07.033628 27903 tablet_server_runner.cc:78] Tablet server non-default flags:
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/wal
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
08:07:07.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.1.keytab
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.1
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.1:0
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data/info.pb
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.1
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.14.62:36401
08:07:07.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.14.1
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/logs
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 06:37:03 UTC on ccycloud.quasar-wifqrz.root.comops.site
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581996
08:07:07.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:07:07.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:07.035166 27903 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:07:07.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:07.036800 27903 file_cache.cc:493] Constructed file cache file cache with capacity 419430
08:07:07.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:07.062817 27914 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
08:07:08.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:07.063282 27916 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
08:07:08.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:07.064013 27913 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
08:07:08.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.205500 27915 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
08:07:08.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.206127 27903 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
08:07:08.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.209925 27903 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:07:08.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.216398 27903 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:07:08.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.218343 27903 hybrid_clock.cc:648] HybridClock initialized: now 1786522028218299 us; error 57 us; skew 500 ppm
08:07:08.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.219324 27903 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:07:08.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:08 dist-test-slave-6bbx krb5kdc[27721](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522028, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.14.1@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:07:08.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.227349 27903 init.cc:377] Logged in from keytab as kudu/127.27.14.1@KRBTEST.COM (short username kudu)
08:07:08.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.234620 27903 webserver.cc:460] Webserver started at http://127.27.14.1:43805/ using document root <none> and password file <none>
08:07:08.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.238677 27903 fs_manager.cc:359] Metadata directory not provided
08:07:08.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.239027 27903 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:07:08.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.240309 27903 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
08:07:08.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.245704 27903 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data/instance:
08:07:08.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "dc41f30d324748d5a1fbfb04febd618c"
08:07:08.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:08 on dist-test-slave-6bbx"
08:07:08.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.247131 27903 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/wal/instance:
08:07:08.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "dc41f30d324748d5a1fbfb04febd618c"
08:07:08.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:08 on dist-test-slave-6bbx"
08:07:08.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.255633 27903 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.006s	sys 0.001s
08:07:08.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.262753 27935 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:07:08.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.264062 27903 fs_manager.cc:725] Time spent opening block manager: real 0.005s	user 0.004s	sys 0.003s
08:07:08.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.264397 27903 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data,/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/wal
08:07:08.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "dc41f30d324748d5a1fbfb04febd618c"
08:07:08.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:08 on dist-test-slave-6bbx"
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.264724 27903 fs_report.cc:383] FS layout report
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/wal
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/wal
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data/data
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:07:08.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:07:08.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:07:08.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:07:08.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:07:08.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.317678 27903 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
08:07:08.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.323736 27903 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
08:07:08.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.324196 27903 kserver.cc:163] Server-wide thread pool size limit: 3276
08:07:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.326784 27903 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
08:07:08.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.331226 27903 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:07:08.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.331511 27903 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
08:07:08.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.331812 27903 ts_tablet_manager.cc:610] Registered 0 tablets
08:07:08.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.331954 27903 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:07:08.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.472613 27903 rpc_server.cc:307] RPC server started. Bound to: 127.27.14.1:46101
08:07:08.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.472745 28119 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.14.1:46101 every 8 connection(s)
08:07:08.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.475272 27903 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/data/info.pb
08:07:08.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.479041 27704 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu as pid 27903
08:07:08.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.479559 27704 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-0/wal/instance
08:07:08.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:08 dist-test-slave-6bbx krb5kdc[27721](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522028, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.14.1@KRBTEST.COM for kudu/127.27.14.62@KRBTEST.COM
08:07:08.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.14.2@KRBTEST.COM; defaulting to no policy
08:07:08.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:08.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.14.2@KRBTEST.COM" created.
08:07:08.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.520030 28123 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
08:07:08.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.480491 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.27.14.62:36401 (local address 127.27.14.1:54815)
08:07:08.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.485061 (+  4570us) negotiation.cc:107] Waiting for socket to connect
08:07:08.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.485166 (+   105us) client_negotiation.cc:174] Beginning negotiation
08:07:08.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.490032 (+  4866us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
08:07:08.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.491351 (+  1319us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.491389 (+    38us) client_negotiation.cc:394] Received NEGOTIATE response from server
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.492406 (+  1017us) client_negotiation.cc:189] Negotiated authn=SASL
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.493732 (+  1326us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.493803 (+    71us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.495611 (+  1808us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.495643 (+    32us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.498403 (+  2760us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.498469 (+    66us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.499977 (+  1508us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.500021 (+    44us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.500571 (+   550us) 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
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.501818 (+  1247us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.501894 (+    76us) client_negotiation.cc:656] calling sasl_client_start()
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.505827 (+  3933us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.512177 (+  6350us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.512205 (+    28us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.512232 (+    27us) client_negotiation.cc:760] calling sasl_client_step()
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.513287 (+  1055us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.514364 (+  1077us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.514390 (+    26us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.514408 (+    18us) client_negotiation.cc:760] calling sasl_client_step()
08:07:08.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.514821 (+   413us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.516595 (+  1774us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.516636 (+    41us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.517143 (+   507us) client_negotiation.cc:769] Sending connection context
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.517563 (+   420us) client_negotiation.cc:240] Negotiation successful
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.517980 (+   417us) negotiation.cc:327] Negotiation complete: OK
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":3949,"thread_start_us":3131,"threads_started":1}
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.520572 28122 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.480425 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.27.14.1:54815 (local address 127.27.14.62:36401)
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.483922 (+  3497us) server_negotiation.cc:206] Beginning negotiation
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.483944 (+    22us) server_negotiation.cc:399] Waiting for connection header
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.485347 (+  1403us) server_negotiation.cc:407] Connection header received
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.490522 (+  5175us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.490548 (+    26us) server_negotiation.cc:461] Received NEGOTIATE request from client
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.490737 (+   189us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.490892 (+   155us) server_negotiation.cc:226] Negotiated authn=SASL
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.494167 (+  3275us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:08.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.495240 (+  1073us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:08.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.498797 (+  3557us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:08.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.499511 (+   714us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:08.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.499796 (+   285us) 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
08:07:08.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.506471 (+  6675us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
08:07:08.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.506494 (+    23us) server_negotiation.cc:881] Received SASL_INITIATE request from client
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.506513 (+    19us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.506628 (+   115us) server_negotiation.cc:922] calling sasl_server_start()
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.511718 (+  5090us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.513720 (+  2002us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.513747 (+    27us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.513766 (+    19us) server_negotiation.cc:967] calling sasl_server_step()
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.514060 (+   294us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.515269 (+  1209us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.515297 (+    28us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.515316 (+    19us) server_negotiation.cc:967] calling sasl_server_step()
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.515995 (+   679us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.516315 (+   320us) server_negotiation.cc:1035] Waiting for connection context
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.518034 (+  1719us) server_negotiation.cc:299] Negotiation successful
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:08.518465 (+   431us) negotiation.cc:327] Negotiation complete: OK
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":2951,"thread_start_us":2580,"threads_started":1}
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.523377 28120 heartbeater.cc:347] Connected to a master server at 127.27.14.62:36401
08:07:08.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.523941 28120 heartbeater.cc:464] Registering TS with master...
08:07:08.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.525223 28120 heartbeater.cc:511] Master 127.27.14.62:36401 requested a full tablet report, sending...
08:07:08.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.529748 27799 ts_manager.cc:194] Registered new tserver with Master: dc41f30d324748d5a1fbfb04febd618c (127.27.14.1:46101)
08:07:08.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.532441 27799 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.27.14.1@KRBTEST.COM'} at 127.27.14.1:54815
08:07:08.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:08.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.2.keytab.
08:07:08.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.2.keytab.
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.540112 27704 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/wal
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:08.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/logs
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data/info.pb
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.2:0
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.14.2
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.2
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:08.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.14.62:36401
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.2.keytab
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.2
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
08:07:08.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
08:07:08.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:07:08.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.848263 28126 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
08:07:08.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.848793 28126 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:07:08.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.849422 28126 flags.cc:425] Enabled unsafe flag: --never_fsync=true
08:07:08.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.880754 28126 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
08:07:08.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.881085 28126 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
08:07:08.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.881956 28126 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.14.2
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.918108 28126 tablet_server_runner.cc:78] Tablet server non-default flags:
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/wal
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
08:07:08.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.2.keytab
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.2
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.2:0
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data/info.pb
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.2
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.14.62:36401
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.14.2
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/logs
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 06:37:03 UTC on ccycloud.quasar-wifqrz.root.comops.site
08:07:08.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581996
08:07:08.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:07:08.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.919431 28126 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:07:08.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.920908 28126 file_cache.cc:493] Constructed file cache file cache with capacity 419430
08:07:08.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.941591 28144 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
08:07:09.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:09.537024 28120 heartbeater.cc:503] Master 127.27.14.62:36401 was elected leader, sending a full tablet report...
08:07:10.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.941627 28137 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
08:07:10.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:08.943027 28126 server_base.cc:1034] running on GCE node
08:07:10.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:08.941633 28138 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
08:07:10.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.033114 28126 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:07:10.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.035444 28126 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:07:10.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.036885 28126 hybrid_clock.cc:648] HybridClock initialized: now 1786522030036854 us; error 49 us; skew 500 ppm
08:07:10.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.037803 28126 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:07:10.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:10 dist-test-slave-6bbx krb5kdc[27721](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522030, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.14.2@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:07:10.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.044361 28126 init.cc:377] Logged in from keytab as kudu/127.27.14.2@KRBTEST.COM (short username kudu)
08:07:10.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.049431 28126 webserver.cc:460] Webserver started at http://127.27.14.2:40203/ using document root <none> and password file <none>
08:07:10.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.050540 28126 fs_manager.cc:359] Metadata directory not provided
08:07:10.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.050801 28126 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:07:10.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.051333 28126 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
08:07:10.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.056469 28126 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data/instance:
08:07:10.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0ca0dba8c020475db95c51aa14b77181"
08:07:10.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:10 on dist-test-slave-6bbx"
08:07:10.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.057928 28126 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/wal/instance:
08:07:10.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0ca0dba8c020475db95c51aa14b77181"
08:07:10.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:10 on dist-test-slave-6bbx"
08:07:10.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.066118 28126 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.000s	sys 0.006s
08:07:10.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.072497 28161 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:07:10.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.073707 28126 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.004s	sys 0.001s
08:07:10.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.074026 28126 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data,/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/wal
08:07:10.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0ca0dba8c020475db95c51aa14b77181"
08:07:10.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:10 on dist-test-slave-6bbx"
08:07:10.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.074452 28126 fs_report.cc:383] FS layout report
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/wal
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/wal
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data/data
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:07:10.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:07:10.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.113482 28126 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
08:07:10.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.119378 28126 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
08:07:10.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.119875 28126 kserver.cc:163] Server-wide thread pool size limit: 3276
08:07:10.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.122794 28126 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
08:07:10.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.127460 28126 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:07:10.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.127753 28126 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.001s
08:07:10.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.128039 28126 ts_tablet_manager.cc:610] Registered 0 tablets
08:07:10.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.128240 28126 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:07:10.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.263600 28126 rpc_server.cc:307] RPC server started. Bound to: 127.27.14.2:44963
08:07:10.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.263753 28364 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.14.2:44963 every 8 connection(s)
08:07:10.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.266736 28126 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/data/info.pb
08:07:10.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.267405 27704 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu as pid 28126
08:07:10.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.267802 27704 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-1/wal/instance
08:07:10.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:10 dist-test-slave-6bbx krb5kdc[27721](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522030, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.14.2@KRBTEST.COM for kudu/127.27.14.62@KRBTEST.COM
08:07:10.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.14.3@KRBTEST.COM; defaulting to no policy
08:07:10.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:10.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.14.3@KRBTEST.COM" created.
08:07:10.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.304414 28373 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
08:07:10.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.272124 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.27.14.2:54517 (local address 127.27.14.62:36401)
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.273439 (+  1315us) server_negotiation.cc:206] Beginning negotiation
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.273464 (+    25us) server_negotiation.cc:399] Waiting for connection header
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.275256 (+  1792us) server_negotiation.cc:407] Connection header received
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.280152 (+  4896us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.280182 (+    30us) server_negotiation.cc:461] Received NEGOTIATE request from client
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.280382 (+   200us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.280556 (+   174us) server_negotiation.cc:226] Negotiated authn=SASL
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.283097 (+  2541us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.284283 (+  1186us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.286610 (+  2327us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.287220 (+   610us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.287478 (+   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
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.292956 (+  5478us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.292982 (+    26us) server_negotiation.cc:881] Received SASL_INITIATE request from client
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.293003 (+    21us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.293102 (+    99us) server_negotiation.cc:922] calling sasl_server_start()
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.297043 (+  3941us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.298963 (+  1920us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.298983 (+    20us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.298997 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.299231 (+   234us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.300265 (+  1034us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.300292 (+    27us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:10.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.300306 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.300838 (+   532us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.301075 (+   237us) server_negotiation.cc:1035] Waiting for connection context
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.302628 (+  1553us) server_negotiation.cc:299] Negotiation successful
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.302956 (+   328us) negotiation.cc:327] Negotiation complete: OK
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":901,"thread_start_us":618,"threads_started":1}
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.306164 28376 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.272405 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.27.14.62:36401 (local address 127.27.14.2:54517)
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.275007 (+  2602us) negotiation.cc:107] Waiting for socket to connect
08:07:10.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.275083 (+    76us) client_negotiation.cc:174] Beginning negotiation
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.279647 (+  4564us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.280907 (+  1260us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.280934 (+    27us) client_negotiation.cc:394] Received NEGOTIATE response from server
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.281799 (+   865us) client_negotiation.cc:189] Negotiated authn=SASL
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.282764 (+   965us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.282817 (+    53us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.284563 (+  1746us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.284584 (+    21us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.286334 (+  1750us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.286376 (+    42us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.287583 (+  1207us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
08:07:10.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.287617 (+    34us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.288119 (+   502us) 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
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.289112 (+   993us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.289179 (+    67us) client_negotiation.cc:656] calling sasl_client_start()
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.292350 (+  3171us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.297486 (+  5136us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.297521 (+    35us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.297558 (+    37us) client_negotiation.cc:760] calling sasl_client_step()
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.298629 (+  1071us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.299526 (+   897us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.299551 (+    25us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.299568 (+    17us) client_negotiation.cc:760] calling sasl_client_step()
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.299986 (+   418us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.301169 (+  1183us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.301216 (+    47us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.301858 (+   642us) client_negotiation.cc:769] Sending connection context
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.302386 (+   528us) client_negotiation.cc:240] Negotiation successful
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:10.302947 (+   561us) negotiation.cc:327] Negotiation complete: OK
08:07:10.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2142,"thread_start_us":1437,"threads_started":1}
08:07:10.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.309602 28366 heartbeater.cc:347] Connected to a master server at 127.27.14.62:36401
08:07:10.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.310086 28366 heartbeater.cc:464] Registering TS with master...
08:07:10.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.311146 28366 heartbeater.cc:511] Master 127.27.14.62:36401 requested a full tablet report, sending...
08:07:10.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.313869 27799 ts_manager.cc:194] Registered new tserver with Master: 0ca0dba8c020475db95c51aa14b77181 (127.27.14.2:44963)
08:07:10.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.315400 27799 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.27.14.2@KRBTEST.COM'} at 127.27.14.2:54517
08:07:10.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:07:10.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.3.keytab.
08:07:10.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.14.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.3.keytab.
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.327798 27704 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/wal
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:10.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/logs
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data/info.pb
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.3:0
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.14.3
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.3
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.14.62:36401
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.3.keytab
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.3
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:07:10.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:07:10.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:10.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:10.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:10.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:10.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
08:07:10.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
08:07:10.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:07:10.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.649611 28380 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
08:07:10.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.650120 28380 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:07:10.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.650715 28380 flags.cc:425] Enabled unsafe flag: --never_fsync=true
08:07:10.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.682500 28380 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
08:07:10.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.682847 28380 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
08:07:10.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.683799 28380 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.14.3
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.720038 28380 tablet_server_runner.cc:78] Tablet server non-default flags:
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.14.20:33381
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/wal
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kudu_127.27.14.3.keytab
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.14.3
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.14.3:0
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
08:07:10.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data/info.pb
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.14.3
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.14.62:36401
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.14.3
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/logs
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 06:37:03 UTC on ccycloud.quasar-wifqrz.root.comops.site
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581996
08:07:10.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:07:10.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.721187 28380 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:07:10.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.722446 28380 file_cache.cc:493] Constructed file cache file cache with capacity 419430
08:07:10.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.745044 28386 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
08:07:11.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.319645 28366 heartbeater.cc:503] Master 127.27.14.62:36401 was elected leader, sending a full tablet report...
08:07:11.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.745944 28387 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
08:07:11.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:10.747475 28380 server_base.cc:1034] running on GCE node
08:07:11.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:10.747741 28389 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
08:07:11.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.845702 28380 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:07:11.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.848500 28380 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:07:11.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.849954 28380 hybrid_clock.cc:648] HybridClock initialized: now 1786522031849911 us; error 52 us; skew 500 ppm
08:07:11.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.850754 28380 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:07:11.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:11 dist-test-slave-6bbx krb5kdc[27721](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522031, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.14.3@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:07:11.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.861196 28380 init.cc:377] Logged in from keytab as kudu/127.27.14.3@KRBTEST.COM (short username kudu)
08:07:11.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.865973 28380 webserver.cc:460] Webserver started at http://127.27.14.3:34357/ using document root <none> and password file <none>
08:07:11.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.867003 28380 fs_manager.cc:359] Metadata directory not provided
08:07:11.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.867271 28380 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:07:11.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.867746 28380 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
08:07:11.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.872866 28380 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data/instance:
08:07:11.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9f5e4159f984407582b4c572818eeae7"
08:07:11.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:11 on dist-test-slave-6bbx"
08:07:11.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.874269 28380 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/wal/instance:
08:07:11.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9f5e4159f984407582b4c572818eeae7"
08:07:11.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:11 on dist-test-slave-6bbx"
08:07:11.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.882005 28380 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.003s
08:07:11.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.887993 28403 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:07:11.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.889189 28380 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.001s
08:07:11.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.889523 28380 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data,/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/wal
08:07:11.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9f5e4159f984407582b4c572818eeae7"
08:07:11.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 08:07:11 on dist-test-slave-6bbx"
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.889855 28380 fs_report.cc:383] FS layout report
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/wal
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/wal
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data/data
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:07:11.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:07:11.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.932966 28380 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
08:07:11.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.939275 28380 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
08:07:11.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.939769 28380 kserver.cc:163] Server-wide thread pool size limit: 3276
08:07:11.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.942919 28380 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
08:07:11.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.947453 28380 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:07:11.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.947734 28380 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.001s	sys 0.000s
08:07:11.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.947955 28380 ts_tablet_manager.cc:610] Registered 0 tablets
08:07:11.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:11.948086 28380 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:07:12.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.095618 28380 rpc_server.cc:307] RPC server started. Bound to: 127.27.14.3:34241
08:07:12.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.095786 28604 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.14.3:34241 every 8 connection(s)
08:07:12.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.098448 28380 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/data/info.pb
08:07:12.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.102560 27704 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu as pid 28380
08:07:12.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.103045 27704 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/ts-2/wal/instance
08:07:12.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 08:07:12 dist-test-slave-6bbx krb5kdc[27721](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786522031, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.14.3@KRBTEST.COM for kudu/127.27.14.62@KRBTEST.COM
08:07:12.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.139286 28607 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
08:07:12.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.103224 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.27.14.3:39481 (local address 127.27.14.62:36401)
08:07:12.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.106849 (+  3625us) server_negotiation.cc:206] Beginning negotiation
08:07:12.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.106871 (+    22us) server_negotiation.cc:399] Waiting for connection header
08:07:12.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.108870 (+  1999us) server_negotiation.cc:407] Connection header received
08:07:12.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.113846 (+  4976us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.113869 (+    23us) server_negotiation.cc:461] Received NEGOTIATE request from client
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.114055 (+   186us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.114206 (+   151us) server_negotiation.cc:226] Negotiated authn=SASL
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.116827 (+  2621us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.117976 (+  1149us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.120405 (+  2429us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.121087 (+   682us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.121377 (+   290us) 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
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.128074 (+  6697us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.128099 (+    25us) server_negotiation.cc:881] Received SASL_INITIATE request from client
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.128119 (+    20us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.128228 (+   109us) server_negotiation.cc:922] calling sasl_server_start()
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.132233 (+  4005us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.133827 (+  1594us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.133847 (+    20us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.133862 (+    15us) server_negotiation.cc:967] calling sasl_server_step()
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134083 (+   221us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134918 (+   835us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134938 (+    20us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134951 (+    13us) server_negotiation.cc:967] calling sasl_server_step()
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.135501 (+   550us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
08:07:12.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.135794 (+   293us) server_negotiation.cc:1035] Waiting for connection context
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.137048 (+  1254us) server_negotiation.cc:299] Negotiation successful
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.137379 (+   331us) negotiation.cc:327] Negotiation complete: OK
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":3194,"thread_start_us":2908,"threads_started":1}
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.140053 28609 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.103650 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.27.14.62:36401 (local address 127.27.14.3:39481)
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.107480 (+  3830us) negotiation.cc:107] Waiting for socket to connect
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.107592 (+   112us) client_negotiation.cc:174] Beginning negotiation
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.113422 (+  5830us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.114476 (+  1054us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.114503 (+    27us) client_negotiation.cc:394] Received NEGOTIATE response from server
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.115448 (+   945us) client_negotiation.cc:189] Negotiated authn=SASL
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.116510 (+  1062us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.116565 (+    55us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.118279 (+  1714us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.118302 (+    23us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.120119 (+  1817us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.120171 (+    52us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.121522 (+  1351us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.121559 (+    37us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.122064 (+   505us) 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
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.123353 (+  1289us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.123438 (+    85us) client_negotiation.cc:656] calling sasl_client_start()
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.127520 (+  4082us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.132647 (+  5127us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.132676 (+    29us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.132703 (+    27us) client_negotiation.cc:760] calling sasl_client_step()
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.133526 (+   823us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134313 (+   787us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134333 (+    20us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134347 (+    14us) client_negotiation.cc:760] calling sasl_client_step()
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.134647 (+   300us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.135785 (+  1138us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
08:07:12.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.135831 (+    46us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
08:07:12.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.136344 (+   513us) client_negotiation.cc:769] Sending connection context
08:07:12.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.136787 (+   443us) client_negotiation.cc:240] Negotiation successful
08:07:12.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0812 08:07:12.137329 (+   542us) negotiation.cc:327] Negotiation complete: OK
08:07:12.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":3186,"thread_start_us":2693,"threads_started":1}
08:07:12.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.142513 28605 heartbeater.cc:347] Connected to a master server at 127.27.14.62:36401
08:07:12.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.142963 28605 heartbeater.cc:464] Registering TS with master...
08:07:12.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.143970 28605 heartbeater.cc:511] Master 127.27.14.62:36401 requested a full tablet report, sending...
08:07:12.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.146306 27799 ts_manager.cc:194] Registered new tserver with Master: 9f5e4159f984407582b4c572818eeae7 (127.27.14.3:34241)
08:07:12.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.147827 27799 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.27.14.3@KRBTEST.COM'} at 127.27.14.3:39481
08:07:12.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.157249 27704 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
08:07:12.164 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
08:07:12.165 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

08:07:12.178 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

08:07:12.180 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

08:07:12.190 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.27.14.62"
      port: 36401
    }
    bound_http_address {
      host: "127.27.14.62"
      port: 41413
    }
  }
}

08:07:12.196 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

08:07:12.200 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.27.14.1"
      port: 46101
    }
    bound_http_address {
      host: "127.27.14.1"
      port: 43805
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.27.14.2"
      port: 44963
    }
    bound_http_address {
      host: "127.27.14.2"
      port: 40203
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.27.14.3"
      port: 34241
    }
    bound_http_address {
      host: "127.27.14.3"
      port: 34357
    }
  }
}

08:07:12.200 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
08:07:12.450 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskKyCRH_/test-tmp/mini-kudu-cluster4869463348440520218/krb5kdc/krb5cc
08:07:12.462 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
08:07:12.477 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
08:07:12.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.480824 27704 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu with pid 27903
08:07:12.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:12.523140 27774 connection.cc:535] server connection from 127.27.14.1:54815 recv error: Network error: failed to read from TLS socket (remote: 127.27.14.1:54815): Connection reset by peer (error 104)
08:07:12.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.523504 27704 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu with pid 28126
08:07:12.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:12.570456 27777 connection.cc:535] server connection from 127.27.14.2:54517 recv error: Network error: failed to read from TLS socket (remote: 127.27.14.2:54517): Connection reset by peer (error 104)
08:07:12.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.570904 27704 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu with pid 28380
08:07:12.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:12.606388 27778 connection.cc:535] server connection from 127.27.14.3:39481 recv error: Network error: failed to read from TLS socket (remote: 127.27.14.3:39481): Connection reset by peer (error 104)
08:07:12.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 08:07:12.606702 27704 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKyCRH_/build/tsan/bin/kudu with pid 27741
08:07:13.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 08:07:12.640715 27718 connection.cc:535] client connection to 127.27.14.62:36401 recv error: Network error: failed to read from TLS socket (remote: 127.27.14.62:36401): Connection reset by peer (error 104)
08:07:13.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T08:07:13Z chronyd exiting

Time: 17.897

OK (1 test)

