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

17:13:20.601 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
17:13:20.602 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

17:13:20.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:20.605316 18167 mini_oidc.cc:152] Starting JWKS server
17:13:20.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:20.609146 18167 webserver.cc:460] Webserver started at http://127.0.0.1:43559/ using document root <none> and password file <none>
17:13:20.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:20.609665 18167 mini_oidc.cc:189] Starting OIDC Discovery server
17:13:20.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:20.611402 18167 webserver.cc:460] Webserver started at http://127.0.0.1:42231/ using document root <none> and password file <none>
17:13:20.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T17:13:20Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
17:13:20.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T17:13:20Z Disabled control of system clock
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:20.657763 18167 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/wal
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/data
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:20.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/logs
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/data/info.pb
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
17:13:20.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.189.254:40043
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.189.254
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.189.212:42511
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:20.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:20.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
17:13:20.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.17.189.254:40043
17:13:20.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
17:13:20.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:43559/jwks.json
17:13:20.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
17:13:20.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
17:13:20.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.949268 18180 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
17:13:20.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.949818 18180 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
17:13:20.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.950356 18180 flags.cc:425] Enabled unsafe flag: --never_fsync=true
17:13:20.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.980952 18180 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
17:13:20.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.981236 18180 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
17:13:20.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.981422 18180 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
17:13:20.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.981647 18180 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
17:13:20.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.981870 18180 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
17:13:20.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:20.982015 18180 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:43559/jwks.json
17:13:21.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:21.023362 18180 master_runner.cc:386] Master server non-default flags:
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.189.212:42511
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/data
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/wal
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
17:13:21.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.17.189.254:40043
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.189.254:40043
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:43559/jwks.json
17:13:21.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/data/info.pb
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.189.254
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/logs
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:21.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 16:08:30 UTC on ccycloud.quasar-mtkpji.root.comops.site
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493844
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
17:13:21.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:21.024612 18180 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
17:13:21.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:21.026291 18180 file_cache.cc:493] Constructed file cache file cache with capacity 419430
17:13:21.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:21.044308 18189 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
17:13:22.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:21.044360 18187 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
17:13:22.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:21.044368 18186 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
17:13:22.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:21.047127 18180 server_base.cc:1034] running on GCE node
17:13:22.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.149632 18180 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
17:13:22.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:22.149879 18180 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
17:13:22.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.150029 18180 hybrid_clock.cc:648] HybridClock initialized: now 1783530802150012 us; error 0 us; skew 500 ppm
17:13:22.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.150959 18180 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
17:13:22.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.158018 18180 webserver.cc:460] Webserver started at http://127.17.189.254:40773/ using document root <none> and password file <none>
17:13:22.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.159164 18180 fs_manager.cc:359] Metadata directory not provided
17:13:22.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.159387 18180 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
17:13:22.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.159782 18180 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
17:13:22.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.164809 18180 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/data/instance:
17:13:22.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "283b7bcd1c754cf1b9e248e26a5b249b"
17:13:22.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:22 on dist-test-slave-1wn7"
17:13:22.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.166159 18180 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/wal/instance:
17:13:22.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "283b7bcd1c754cf1b9e248e26a5b249b"
17:13:22.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:22 on dist-test-slave-1wn7"
17:13:22.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.173799 18180 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.009s	sys 0.000s
17:13:22.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.179423 18195 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
17:13:22.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.180548 18180 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.004s	sys 0.000s
17:13:22.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.180917 18180 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/data,/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/wal
17:13:22.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "283b7bcd1c754cf1b9e248e26a5b249b"
17:13:22.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:22 on dist-test-slave-1wn7"
17:13:22.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:22.181322 18180 fs_report.cc:383] FS layout report
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/wal
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/wal
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster320028214946976948/master-0/data/data
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
17:13:22.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
17:13:22.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
17:13:22.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
17:13:22.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
17:13:22.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
17:13:22.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
17:13:22.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
17:13:22.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
17:13:22.233 [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
17:13:23.265 [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-taskjxDx7f/build/tsan/bin/kudu: process exited with non-zero status 1"
}

17:13:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T17:13:24Z chronyd exiting
17:13:25.349 [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-taskjxDx7f/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]
17:13:25.357 [INFO - main] (RandomUtils.java:49) Using random seed: 1783530805357
17:13:25.365 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
17:13:25.420 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
17:13:25.421 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskjxDx7f/test-tmp
17:13:25.421 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
17:13:25.422 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskjxDx7f/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
17:13:25.423 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265"
  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 {
  }
}

17:13:25.732 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
17:13:25.732 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

17:13:25.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:25.736621 18198 mini_oidc.cc:152] Starting JWKS server
17:13:25.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:25.740097 18198 webserver.cc:460] Webserver started at http://127.0.0.1:34887/ using document root <none> and password file <none>
17:13:25.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:25.740545 18198 mini_oidc.cc:189] Starting OIDC Discovery server
17:13:25.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:25.742247 18198 webserver.cc:460] Webserver started at http://127.0.0.1:35405/ using document root <none> and password file <none>
17:13:25.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
17:13:25.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/principal' for realm 'KRBTEST.COM',
17:13:25.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
17:13:25.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:25 dist-test-slave-1wn7 krb5kdc[18209](info): setting up network...
17:13:25.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
17:13:25.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:25 dist-test-slave-1wn7 krb5kdc[18209](info): set up 2 sockets
17:13:25.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:25 dist-test-slave-1wn7 krb5kdc[18209](info): commencing operation
17:13:25.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
17:13:26.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:26.729043 18198 mini_kdc.cc:121] Time spent starting KDC: real 0.962s	user 0.001s	sys 0.019s
17:13:26.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
17:13:26.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
17:13:26.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
17:13:26.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
17:13:26.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
17:13:26.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
17:13:26.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
17:13:26.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
17:13:26.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
17:13:26.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
17:13:26.825 [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-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/test-user.keytab.
17:13:26.825 [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-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/test-user.keytab.
17:13:26.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:26 dist-test-slave-1wn7 krb5kdc[18209](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530806, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
17:13:26.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
17:13:26.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T17:13:26Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
17:13:26.862 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T17:13:26Z Disabled control of system clock
17:13:26.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.197.190@KRBTEST.COM; defaulting to no policy
17:13:26.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:26.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.197.190@KRBTEST.COM" created.
17:13:26.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:26.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.190 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.190.keytab.
17:13:26.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.190 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.190.keytab.
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:26.907766 18198 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/wal
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
17:13:26.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/logs
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data/info.pb
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
17:13:26.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.190:39309
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.190
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.190.keytab
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.190
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.17.197.190:39309
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:34887/jwks.json
17:13:26.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
17:13:26.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
17:13:26.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
17:13:27.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.196141 18222 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
17:13:27.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.196797 18222 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
17:13:27.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.197631 18222 flags.cc:425] Enabled unsafe flag: --never_fsync=true
17:13:27.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.228403 18222 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
17:13:27.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.228821 18222 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
17:13:27.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.229226 18222 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
17:13:27.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.229557 18222 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
17:13:27.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.229882 18222 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
17:13:27.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.230193 18222 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
17:13:27.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.230437 18222 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:34887/jwks.json
17:13:27.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:27.266448 18222 master_runner.cc:386] Master server non-default flags:
17:13:27.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:27.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:27.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:27.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:27.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data
17:13:27.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/wal
17:13:27.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.17.197.190:39309
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.190.keytab
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.190
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.190:39309
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
17:13:27.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
17:13:27.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
17:13:27.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:34887/jwks.json
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data/info.pb
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.190
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:27.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/logs
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
17:13:27.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
17:13:27.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 16:08:30 UTC on ccycloud.quasar-mtkpji.root.comops.site
17:13:27.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493844
17:13:27.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
17:13:27.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:27.267932 18222 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
17:13:27.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:27.269428 18222 file_cache.cc:493] Constructed file cache file cache with capacity 419430
17:13:27.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.287313 18231 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
17:13:28.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.287338 18228 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
17:13:28.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:27.289580 18222 server_base.cc:1034] running on GCE node
17:13:28.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:27.287747 18229 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
17:13:28.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.387437 18222 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
17:13:28.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.390430 18222 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
17:13:28.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.391887 18222 hybrid_clock.cc:648] HybridClock initialized: now 1783530808391837 us; error 66 us; skew 500 ppm
17:13:28.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.392728 18222 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
17:13:28.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:28 dist-test-slave-1wn7 krb5kdc[18209](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530808, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.197.190@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
17:13:28.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.402379 18222 init.cc:377] Logged in from keytab as kudu/127.17.197.190@KRBTEST.COM (short username kudu)
17:13:28.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.408123 18222 webserver.cc:460] Webserver started at http://127.17.197.190:45403/ using document root <none> and password file <none>
17:13:28.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.409195 18222 fs_manager.cc:359] Metadata directory not provided
17:13:28.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.409431 18222 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
17:13:28.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.409965 18222 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
17:13:28.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.415062 18222 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data/instance:
17:13:28.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8a2b781bdfa44b22bb1c854fef4f8f53"
17:13:28.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:28 on dist-test-slave-1wn7"
17:13:28.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.416571 18222 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/wal/instance:
17:13:28.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8a2b781bdfa44b22bb1c854fef4f8f53"
17:13:28.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:28 on dist-test-slave-1wn7"
17:13:28.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.424237 18222 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.007s	sys 0.001s
17:13:28.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.430011 18239 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
17:13:28.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.431208 18222 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
17:13:28.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.431576 18222 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data,/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/wal
17:13:28.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8a2b781bdfa44b22bb1c854fef4f8f53"
17:13:28.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:28 on dist-test-slave-1wn7"
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.431978 18222 fs_report.cc:383] FS layout report
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/wal
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/wal
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data/data
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
17:13:28.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
17:13:28.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
17:13:28.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
17:13:28.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
17:13:28.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
17:13:28.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
17:13:28.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
17:13:28.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.472628 18222 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
17:13:28.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.478828 18222 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
17:13:28.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.479383 18222 kserver.cc:163] Server-wide thread pool size limit: 3276
17:13:28.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.547396 18222 rpc_server.cc:307] RPC server started. Bound to: 127.17.197.190:39309
17:13:28.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.547489 18290 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.17.197.190:39309 every 8 connection(s)
17:13:28.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.550101 18222 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/data/info.pb
17:13:28.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.555428 18291 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
17:13:28.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.557246 18198 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu as pid 18222
17:13:28.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.557794 18198 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/master-0/wal/instance
17:13:28.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.577142 18291 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53: Bootstrap starting.
17:13:28.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.582540 18291 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53: Neither blocks nor log segments found. Creating new log.
17:13:28.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.584481 18291 log.cc:824] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53: Log is configured to *not* fsync() on all Append() calls
17:13:28.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.590315 18291 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53: No bootstrap required, opened a new log
17:13:28.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:28 dist-test-slave-1wn7 krb5kdc[18209](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530806, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.17.197.190@KRBTEST.COM
17:13:28.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.612000 18294 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
17:13:28.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.564567 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:51154 (local address 127.17.197.190:39309)
17:13:28.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.568599 (+  4032us) server_negotiation.cc:206] Beginning negotiation
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.568641 (+    42us) server_negotiation.cc:399] Waiting for connection header
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.568723 (+    82us) server_negotiation.cc:407] Connection header received
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.577866 (+  9143us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.577911 (+    45us) server_negotiation.cc:461] Received NEGOTIATE request from client
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.578317 (+   406us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.578716 (+   399us) server_negotiation.cc:226] Negotiated authn=SASL
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.582834 (+  4118us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.584650 (+  1816us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.587688 (+  3038us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.589193 (+  1505us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.589558 (+   365us) 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
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.596707 (+  7149us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.596746 (+    39us) server_negotiation.cc:881] Received SASL_INITIATE request from client
17:13:28.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.596789 (+    43us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
17:13:28.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.596944 (+   155us) server_negotiation.cc:922] calling sasl_server_start()
17:13:28.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.602837 (+  5893us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:28.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.604475 (+  1638us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:28.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.604515 (+    40us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:28.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.604542 (+    27us) server_negotiation.cc:967] calling sasl_server_step()
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.604895 (+   353us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.605745 (+   850us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.605774 (+    29us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.605796 (+    22us) server_negotiation.cc:967] calling sasl_server_step()
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.606825 (+  1029us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.607444 (+   619us) server_negotiation.cc:1035] Waiting for connection context
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.608588 (+  1144us) server_negotiation.cc:299] Negotiation successful
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:28.609106 (+   518us) negotiation.cc:327] Negotiation complete: OK
17:13:28.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":3453,"thread_start_us":2963,"threads_started":1}
17:13:28.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.617075 18291 raft_consensus.cc:348] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8a2b781bdfa44b22bb1c854fef4f8f53" member_type: VOTER last_known_addr { host: "127.17.197.190" port: 39309 } }
17:13:28.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.618002 18291 raft_consensus.cc:374] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
17:13:28.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.618310 18291 raft_consensus.cc:729] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8a2b781bdfa44b22bb1c854fef4f8f53, State: Initialized, Role: FOLLOWER
17:13:28.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.619215 18291 consensus_queue.cc:260] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [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: "8a2b781bdfa44b22bb1c854fef4f8f53" member_type: VOTER last_known_addr { host: "127.17.197.190" port: 39309 } }
17:13:28.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.619944 18291 raft_consensus.cc:388] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
17:13:28.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.620265 18291 raft_consensus.cc:482] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
17:13:28.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.620637 18291 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 0 FOLLOWER]: Advancing to term 1
17:13:28.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.625607 18291 raft_consensus.cc:504] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8a2b781bdfa44b22bb1c854fef4f8f53" member_type: VOTER last_known_addr { host: "127.17.197.190" port: 39309 } }
17:13:28.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.626474 18291 leader_election.cc:302] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [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: 8a2b781bdfa44b22bb1c854fef4f8f53; no voters: 
17:13:28.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.628264 18291 leader_election.cc:288] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [CANDIDATE]: Term 1 election: Requested vote from peers 
17:13:28.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.628667 18296 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 1 FOLLOWER]: Leader election won for term 1
17:13:28.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.631520 18296 raft_consensus.cc:686] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [term 1 LEADER]: Becoming Leader. State: Replica: 8a2b781bdfa44b22bb1c854fef4f8f53, State: Running, Role: LEADER
17:13:28.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.632411 18296 consensus_queue.cc:237] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [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: "8a2b781bdfa44b22bb1c854fef4f8f53" member_type: VOTER last_known_addr { host: "127.17.197.190" port: 39309 } }
17:13:28.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.632863 18291 sys_catalog.cc:564] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [sys.catalog]: configured and running, proceeding with master startup.
17:13:28.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.647159 18297 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "8a2b781bdfa44b22bb1c854fef4f8f53" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8a2b781bdfa44b22bb1c854fef4f8f53" member_type: VOTER last_known_addr { host: "127.17.197.190" port: 39309 } } }
17:13:28.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.647326 18298 sys_catalog.cc:455] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 8a2b781bdfa44b22bb1c854fef4f8f53. Latest consensus state: current_term: 1 leader_uuid: "8a2b781bdfa44b22bb1c854fef4f8f53" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "8a2b781bdfa44b22bb1c854fef4f8f53" member_type: VOTER last_known_addr { host: "127.17.197.190" port: 39309 } } }
17:13:28.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.648413 18298 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [sys.catalog]: This master's current role is: LEADER
17:13:28.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.654254 18297 sys_catalog.cc:458] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53 [sys.catalog]: This master's current role is: LEADER
17:13:28.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.660439 18306 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
17:13:28.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.669588 18306 catalog_manager.cc:1484] Initializing Kudu cluster ID...
17:13:28.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.685693 18306 catalog_manager.cc:1347] Generated new cluster ID: f0998e8e6d524a5f904482023e899a65
17:13:28.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.686040 18306 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
17:13:28.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.715847 18306 catalog_manager.cc:1370] Generated new certificate authority record
17:13:28.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.718484 18306 catalog_manager.cc:1504] Loading token signing keys...
17:13:28.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.731097 18306 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 8a2b781bdfa44b22bb1c854fef4f8f53: Generated new TSK 0
17:13:28.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.732203 18306 catalog_manager.cc:1514] Initializing in-progress tserver states...
17:13:28.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.197.129@KRBTEST.COM; defaulting to no policy
17:13:28.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:28.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.197.129@KRBTEST.COM" created.
17:13:28.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:28.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.129 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.129.keytab.
17:13:28.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.129 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.129.keytab.
17:13:28.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:28.794951 18198 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:28.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:28.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/wal
17:13:28.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data
17:13:28.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
17:13:28.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
17:13:28.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/logs
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data/info.pb
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.129:0
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.197.129
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.129
17:13:28.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.197.190:39309
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.129.keytab
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.129
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
17:13:28.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
17:13:29.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.091817 18317 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
17:13:29.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.092278 18317 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
17:13:29.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.092888 18317 flags.cc:425] Enabled unsafe flag: --never_fsync=true
17:13:29.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.124419 18317 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
17:13:29.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.124742 18317 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
17:13:29.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.125604 18317 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.17.197.129
17:13:29.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:29.163942 18317 tablet_server_runner.cc:78] Tablet server non-default flags:
17:13:29.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:29.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:29.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:29.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:29.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data
17:13:29.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/wal
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.129.keytab
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.129
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.129:0
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data/info.pb
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.129
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.197.190:39309
17:13:29.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.197.129
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/logs
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 16:08:30 UTC on ccycloud.quasar-mtkpji.root.comops.site
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493844
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
17:13:29.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:29.165306 18317 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
17:13:29.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:29.166729 18317 file_cache.cc:493] Constructed file cache file cache with capacity 419430
17:13:29.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.190187 18324 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
17:13:30.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.191376 18323 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
17:13:30.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:29.191666 18326 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
17:13:30.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:29.193616 18317 server_base.cc:1034] running on GCE node
17:13:30.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.300640 18317 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
17:13:30.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.303485 18317 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
17:13:30.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.305262 18317 hybrid_clock.cc:648] HybridClock initialized: now 1783530810305221 us; error 43 us; skew 500 ppm
17:13:30.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.306360 18317 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
17:13:30.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:30 dist-test-slave-1wn7 krb5kdc[18209](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530810, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.197.129@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
17:13:30.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.313658 18317 init.cc:377] Logged in from keytab as kudu/127.17.197.129@KRBTEST.COM (short username kudu)
17:13:30.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.319514 18317 webserver.cc:460] Webserver started at http://127.17.197.129:37895/ using document root <none> and password file <none>
17:13:30.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.320744 18317 fs_manager.cc:359] Metadata directory not provided
17:13:30.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.321012 18317 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
17:13:30.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.321544 18317 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
17:13:30.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.328990 18317 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data/instance:
17:13:30.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f21286198a4a4bdda7d967b9f9e03b7c"
17:13:30.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:30 on dist-test-slave-1wn7"
17:13:30.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.330642 18317 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/wal/instance:
17:13:30.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f21286198a4a4bdda7d967b9f9e03b7c"
17:13:30.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:30 on dist-test-slave-1wn7"
17:13:30.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.340714 18317 fs_manager.cc:691] Time spent creating directory manager: real 0.009s	user 0.008s	sys 0.000s
17:13:30.348 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.348059 18334 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
17:13:30.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.349421 18317 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.002s
17:13:30.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.349854 18317 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data,/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/wal
17:13:30.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "f21286198a4a4bdda7d967b9f9e03b7c"
17:13:30.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:30 on dist-test-slave-1wn7"
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.350317 18317 fs_report.cc:383] FS layout report
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/wal
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/wal
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data/data
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
17:13:30.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
17:13:30.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.400682 18317 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
17:13:30.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.407284 18317 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
17:13:30.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.407830 18317 kserver.cc:163] Server-wide thread pool size limit: 3276
17:13:30.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.410775 18317 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
17:13:30.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.415115 18317 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
17:13:30.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.415351 18317 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
17:13:30.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.415668 18317 ts_tablet_manager.cc:610] Registered 0 tablets
17:13:30.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.415874 18317 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
17:13:30.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.558415 18317 rpc_server.cc:307] RPC server started. Bound to: 127.17.197.129:40971
17:13:30.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.558542 18446 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.17.197.129:40971 every 8 connection(s)
17:13:30.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.561082 18317 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/data/info.pb
17:13:30.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.570689 18198 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu as pid 18317
17:13:30.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.571094 18198 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-0/wal/instance
17:13:30.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:30 dist-test-slave-1wn7 krb5kdc[18209](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530810, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.197.129@KRBTEST.COM for kudu/127.17.197.190@KRBTEST.COM
17:13:30.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.197.130@KRBTEST.COM; defaulting to no policy
17:13:30.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:30.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.197.130@KRBTEST.COM" created.
17:13:30.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.601348 18449 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
17:13:30.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.565765 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.17.197.190:39309 (local address 127.17.197.129:47957)
17:13:30.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.568615 (+  2850us) negotiation.cc:107] Waiting for socket to connect
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.568698 (+    83us) client_negotiation.cc:174] Beginning negotiation
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.573378 (+  4680us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.574441 (+  1063us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.574479 (+    38us) client_negotiation.cc:394] Received NEGOTIATE response from server
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.575596 (+  1117us) client_negotiation.cc:189] Negotiated authn=SASL
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.576563 (+   967us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.576614 (+    51us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.578856 (+  2242us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.578877 (+    21us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.580601 (+  1724us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.580645 (+    44us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.582195 (+  1550us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.582230 (+    35us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.582710 (+   480us) 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
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.583758 (+  1048us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.583918 (+   160us) client_negotiation.cc:656] calling sasl_client_start()
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.588055 (+  4137us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.594013 (+  5958us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.594051 (+    38us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.594102 (+    51us) client_negotiation.cc:760] calling sasl_client_step()
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.595220 (+  1118us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.596156 (+   936us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
17:13:30.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.596186 (+    30us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.596206 (+    20us) client_negotiation.cc:760] calling sasl_client_step()
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.596616 (+   410us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.597801 (+  1185us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.597842 (+    41us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.598474 (+   632us) client_negotiation.cc:769] Sending connection context
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.598943 (+   469us) client_negotiation.cc:240] Negotiation successful
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.599372 (+   429us) negotiation.cc:327] Negotiation complete: OK
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2220,"thread_start_us":1441,"threads_started":1}
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.601996 18450 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.567845 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.17.197.129:47957 (local address 127.17.197.190:39309)
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.569038 (+  1193us) server_negotiation.cc:206] Beginning negotiation
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.569055 (+    17us) server_negotiation.cc:399] Waiting for connection header
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.569095 (+    40us) server_negotiation.cc:407] Connection header received
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.573748 (+  4653us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.573769 (+    21us) server_negotiation.cc:461] Received NEGOTIATE request from client
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.573938 (+   169us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.574080 (+   142us) server_negotiation.cc:226] Negotiated authn=SASL
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.576892 (+  2812us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.578595 (+  1703us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:30.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.580925 (+  2330us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.581809 (+   884us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.582081 (+   272us) 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
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.588661 (+  6580us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.588683 (+    22us) server_negotiation.cc:881] Received SASL_INITIATE request from client
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.588705 (+    22us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.588805 (+   100us) server_negotiation.cc:922] calling sasl_server_start()
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.593504 (+  4699us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.595586 (+  2082us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:30.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.595607 (+    21us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.595621 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.595845 (+   224us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.596898 (+  1053us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.596920 (+    22us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.596934 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.597456 (+   522us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.597719 (+   263us) server_negotiation.cc:1035] Waiting for connection context
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.599362 (+  1643us) server_negotiation.cc:299] Negotiation successful
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:30.599786 (+   424us) negotiation.cc:327] Negotiation complete: OK
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"mutex_wait_us":26,"server-negotiator.queue_time_us":742,"thread_start_us":492,"threads_started":1}
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.604614 18447 heartbeater.cc:347] Connected to a master server at 127.17.197.190:39309
17:13:30.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.605105 18447 heartbeater.cc:464] Registering TS with master...
17:13:30.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.606281 18447 heartbeater.cc:511] Master 127.17.197.190:39309 requested a full tablet report, sending...
17:13:30.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.609875 18256 ts_manager.cc:194] Registered new tserver with Master: f21286198a4a4bdda7d967b9f9e03b7c (127.17.197.129:40971)
17:13:30.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.612425 18256 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.17.197.129@KRBTEST.COM'} at 127.17.197.129:47957
17:13:30.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:30.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.130 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.130.keytab.
17:13:30.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.130 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.130.keytab.
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.620748 18198 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/wal
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/logs
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data/info.pb
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
17:13:30.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.130:0
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.197.130
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.130
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.197.190:39309
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.130.keytab
17:13:30.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.130
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
17:13:30.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
17:13:30.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:30.819564 18287 debug-util.cc:398] Leaking SignalData structure 0x7b0800073c60 after lost signal to thread 18223
17:13:30.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:30.912545 18453 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
17:13:30.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:30.913087 18453 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
17:13:30.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:30.913857 18453 flags.cc:425] Enabled unsafe flag: --never_fsync=true
17:13:30.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:30.944418 18453 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
17:13:30.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:30.944818 18453 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
17:13:30.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:30.946254 18453 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.17.197.130
17:13:30.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.982412 18453 tablet_server_runner.cc:78] Tablet server non-default flags:
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/wal
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.130.keytab
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.130
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.130:0
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data/info.pb
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.130
17:13:30.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.197.190:39309
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.197.130
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/logs
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 16:08:30 UTC on ccycloud.quasar-mtkpji.root.comops.site
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493844
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.983563 18453 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
17:13:30.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:30.984644 18453 file_cache.cc:493] Constructed file cache file cache with capacity 419430
17:13:31.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:31.005267 18459 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
17:13:31.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:31.616740 18447 heartbeater.cc:503] Master 127.17.197.190:39309 was elected leader, sending a full tablet report...
17:13:32.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:31.005390 18460 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
17:13:32.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:31.011538 18462 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
17:13:32.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.145156 18461 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
17:13:32.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.145287 18453 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
17:13:32.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.146567 18453 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
17:13:32.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.149233 18453 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
17:13:32.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.150648 18453 hybrid_clock.cc:648] HybridClock initialized: now 1783530812150590 us; error 78 us; skew 500 ppm
17:13:32.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.151638 18453 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
17:13:32.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:32 dist-test-slave-1wn7 krb5kdc[18209](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530812, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.197.130@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
17:13:32.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.157442 18453 init.cc:377] Logged in from keytab as kudu/127.17.197.130@KRBTEST.COM (short username kudu)
17:13:32.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.163110 18453 webserver.cc:460] Webserver started at http://127.17.197.130:39171/ using document root <none> and password file <none>
17:13:32.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.164108 18453 fs_manager.cc:359] Metadata directory not provided
17:13:32.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.164332 18453 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
17:13:32.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.164825 18453 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
17:13:32.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.169608 18453 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data/instance:
17:13:32.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "09c3dada5a814e14abd13effb66c24e8"
17:13:32.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:32 on dist-test-slave-1wn7"
17:13:32.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.170876 18453 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/wal/instance:
17:13:32.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "09c3dada5a814e14abd13effb66c24e8"
17:13:32.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:32 on dist-test-slave-1wn7"
17:13:32.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.178704 18453 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.000s
17:13:32.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.184572 18470 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.185730 18453 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.002s
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.186024 18453 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data,/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/wal
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "09c3dada5a814e14abd13effb66c24e8"
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:32 on dist-test-slave-1wn7"
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.186331 18453 fs_report.cc:383] FS layout report
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/wal
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/wal
17:13:32.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data/data
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
17:13:32.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
17:13:32.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.234745 18453 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
17:13:32.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.240705 18453 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
17:13:32.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.241140 18453 kserver.cc:163] Server-wide thread pool size limit: 3276
17:13:32.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.244025 18453 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
17:13:32.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.249795 18453 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
17:13:32.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.250109 18453 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
17:13:32.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.250401 18453 ts_tablet_manager.cc:610] Registered 0 tablets
17:13:32.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.250603 18453 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
17:13:32.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.413275 18453 rpc_server.cc:307] RPC server started. Bound to: 127.17.197.130:44523
17:13:32.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.413475 18582 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.17.197.130:44523 every 8 connection(s)
17:13:32.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.415987 18453 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/data/info.pb
17:13:32.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.423264 18198 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu as pid 18453
17:13:32.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.423663 18198 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-1/wal/instance
17:13:32.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.197.131@KRBTEST.COM; defaulting to no policy
17:13:32.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:32.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.197.131@KRBTEST.COM" created.
17:13:32.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:32 dist-test-slave-1wn7 krb5kdc[18209](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530812, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.197.130@KRBTEST.COM for kudu/127.17.197.190@KRBTEST.COM
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.461766 18585 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.421235 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.17.197.130:51511 (local address 127.17.197.190:39309)
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.422653 (+  1418us) server_negotiation.cc:206] Beginning negotiation
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.422675 (+    22us) server_negotiation.cc:399] Waiting for connection header
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.424518 (+  1843us) server_negotiation.cc:407] Connection header received
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.431657 (+  7139us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.431687 (+    30us) server_negotiation.cc:461] Received NEGOTIATE request from client
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.431921 (+   234us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.432122 (+   201us) server_negotiation.cc:226] Negotiated authn=SASL
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.435552 (+  3430us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.437100 (+  1548us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.440592 (+  3492us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.441654 (+  1062us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.441965 (+   311us) 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
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.449153 (+  7188us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.449194 (+    41us) server_negotiation.cc:881] Received SASL_INITIATE request from client
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.449225 (+    31us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.449379 (+   154us) server_negotiation.cc:922] calling sasl_server_start()
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.453954 (+  4575us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.455598 (+  1644us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:32.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.455619 (+    21us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.455632 (+    13us) server_negotiation.cc:967] calling sasl_server_step()
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.455846 (+   214us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.456795 (+   949us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.456814 (+    19us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.456828 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.457342 (+   514us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.457630 (+   288us) server_negotiation.cc:1035] Waiting for connection context
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.459042 (+  1412us) server_negotiation.cc:299] Negotiation successful
17:13:32.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.459539 (+   497us) negotiation.cc:327] Negotiation complete: OK
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":953,"thread_start_us":581,"threads_started":1}
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.462407 18586 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.421418 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.17.197.190:39309 (local address 127.17.197.130:51511)
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.424212 (+  2794us) negotiation.cc:107] Waiting for socket to connect
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.424308 (+    96us) client_negotiation.cc:174] Beginning negotiation
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.431131 (+  6823us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.432467 (+  1336us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.432507 (+    40us) client_negotiation.cc:394] Received NEGOTIATE response from server
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.433884 (+  1377us) client_negotiation.cc:189] Negotiated authn=SASL
17:13:32.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.435178 (+  1294us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.435245 (+    67us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.437446 (+  2201us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.437478 (+    32us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.440211 (+  2733us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.440274 (+    63us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.442048 (+  1774us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.442084 (+    36us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.442645 (+   561us) 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
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.443858 (+  1213us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.444001 (+   143us) client_negotiation.cc:656] calling sasl_client_start()
17:13:32.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.448449 (+  4448us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.454363 (+  5914us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.454386 (+    23us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.454412 (+    26us) client_negotiation.cc:760] calling sasl_client_step()
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.455244 (+   832us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.456150 (+   906us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.456168 (+    18us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.456183 (+    15us) client_negotiation.cc:760] calling sasl_client_step()
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.456478 (+   295us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.457672 (+  1194us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.457715 (+    43us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.458195 (+   480us) client_negotiation.cc:769] Sending connection context
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.458685 (+   490us) client_negotiation.cc:240] Negotiation successful
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:32.459229 (+   544us) negotiation.cc:327] Negotiation complete: OK
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2208,"thread_start_us":3791,"threads_started":1}
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.466776 18583 heartbeater.cc:347] Connected to a master server at 127.17.197.190:39309
17:13:32.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.467316 18583 heartbeater.cc:464] Registering TS with master...
17:13:32.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.468686 18583 heartbeater.cc:511] Master 127.17.197.190:39309 requested a full tablet report, sending...
17:13:32.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.472074 18256 ts_manager.cc:194] Registered new tserver with Master: 09c3dada5a814e14abd13effb66c24e8 (127.17.197.130:44523)
17:13:32.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.474226 18256 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.17.197.130@KRBTEST.COM'} at 127.17.197.130:51511
17:13:32.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
17:13:32.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.131 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.131.keytab.
17:13:32.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.197.131 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.131.keytab.
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.480782 18198 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/wal
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/logs
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data/info.pb
17:13:32.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.131:0
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.197.131
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.131
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.197.190:39309
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.131.keytab
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.131
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
17:13:32.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
17:13:32.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.781157 18589 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
17:13:32.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.781621 18589 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
17:13:32.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.782192 18589 flags.cc:425] Enabled unsafe flag: --never_fsync=true
17:13:32.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.812831 18589 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
17:13:32.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.813148 18589 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
17:13:32.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.814006 18589 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.17.197.131
17:13:32.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.849159 18589 tablet_server_runner.cc:78] Tablet server non-default flags:
17:13:32.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
17:13:32.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.197.148:44189
17:13:32.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
17:13:32.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
17:13:32.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/wal
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kudu_127.17.197.131.keytab
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.197.131
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.197.131:0
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data/info.pb
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.197.131
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.197.190:39309
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
17:13:32.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.197.131
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/logs
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 16:08:30 UTC on ccycloud.quasar-mtkpji.root.comops.site
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493844
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
17:13:32.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.850373 18589 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
17:13:32.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.851802 18589 file_cache.cc:493] Constructed file cache file cache with capacity 419430
17:13:32.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.874327 18596 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
17:13:33.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.479285 18583 heartbeater.cc:503] Master 127.17.197.190:39309 was elected leader, sending a full tablet report...
17:13:33.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.874369 18598 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
17:13:33.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:32.876593 18589 server_base.cc:1034] running on GCE node
17:13:33.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:32.874439 18595 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
17:13:33.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.967963 18589 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
17:13:33.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.970185 18589 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
17:13:33.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.971541 18589 hybrid_clock.cc:648] HybridClock initialized: now 1783530813971508 us; error 53 us; skew 500 ppm
17:13:33.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.972344 18589 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
17:13:33.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:33 dist-test-slave-1wn7 krb5kdc[18209](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530813, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.197.131@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
17:13:33.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.982802 18589 init.cc:377] Logged in from keytab as kudu/127.17.197.131@KRBTEST.COM (short username kudu)
17:13:33.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.987846 18589 webserver.cc:460] Webserver started at http://127.17.197.131:44473/ using document root <none> and password file <none>
17:13:33.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.988754 18589 fs_manager.cc:359] Metadata directory not provided
17:13:33.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.988951 18589 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
17:13:33.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.989327 18589 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
17:13:33.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.994166 18589 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data/instance:
17:13:33.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ce5058ec90604829adfaffc21265fd7a"
17:13:33.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:33 on dist-test-slave-1wn7"
17:13:33.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:33.995326 18589 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/wal/instance:
17:13:33.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ce5058ec90604829adfaffc21265fd7a"
17:13:33.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:33 on dist-test-slave-1wn7"
17:13:34.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.002550 18589 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.002s	sys 0.004s
17:13:34.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.008193 18606 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
17:13:34.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.009366 18589 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.000s
17:13:34.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.009666 18589 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data,/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/wal
17:13:34.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ce5058ec90604829adfaffc21265fd7a"
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 17:13:33 on dist-test-slave-1wn7"
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.009966 18589 fs_report.cc:383] FS layout report
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/wal
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/wal
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data/data
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
17:13:34.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
17:13:34.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
17:13:34.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
17:13:34.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
17:13:34.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.097662 18589 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
17:13:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.103384 18589 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
17:13:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.103792 18589 kserver.cc:163] Server-wide thread pool size limit: 3276
17:13:34.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.106237 18589 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
17:13:34.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.110213 18589 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
17:13:34.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.110422 18589 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
17:13:34.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.110671 18589 ts_tablet_manager.cc:610] Registered 0 tablets
17:13:34.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.110867 18589 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
17:13:34.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.240734 18589 rpc_server.cc:307] RPC server started. Bound to: 127.17.197.131:40157
17:13:34.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.240851 18718 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.17.197.131:40157 every 8 connection(s)
17:13:34.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.243171 18589 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/data/info.pb
17:13:34.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.248579 18198 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu as pid 18589
17:13:34.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.248983 18198 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/ts-2/wal/instance
17:13:34.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 17:13:34 dist-test-slave-1wn7 krb5kdc[18209](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783530813, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.197.131@KRBTEST.COM for kudu/127.17.197.190@KRBTEST.COM
17:13:34.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.278628 18721 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.247283 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.17.197.131:60807 (local address 127.17.197.190:39309)
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.248565 (+  1282us) server_negotiation.cc:206] Beginning negotiation
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.248588 (+    23us) server_negotiation.cc:399] Waiting for connection header
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.250658 (+  2070us) server_negotiation.cc:407] Connection header received
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.256100 (+  5442us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.256120 (+    20us) server_negotiation.cc:461] Received NEGOTIATE request from client
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.256285 (+   165us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.256433 (+   148us) server_negotiation.cc:226] Negotiated authn=SASL
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.258610 (+  2177us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.259726 (+  1116us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.261924 (+  2198us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.262556 (+   632us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.262777 (+   221us) 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
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.268181 (+  5404us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.268206 (+    25us) server_negotiation.cc:881] Received SASL_INITIATE request from client
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.268228 (+    22us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.268326 (+    98us) server_negotiation.cc:922] calling sasl_server_start()
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.272378 (+  4052us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.273759 (+  1381us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.273780 (+    21us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.273794 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274017 (+   223us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274914 (+   897us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
17:13:34.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274934 (+    20us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274948 (+    14us) server_negotiation.cc:967] calling sasl_server_step()
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.275518 (+   570us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.275758 (+   240us) server_negotiation.cc:1035] Waiting for connection context
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.276920 (+  1162us) server_negotiation.cc:299] Negotiation successful
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.277221 (+   301us) negotiation.cc:327] Negotiation complete: OK
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":868,"thread_start_us":563,"threads_started":1}
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.279166 18722 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.247546 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.17.197.190:39309 (local address 127.17.197.131:60807)
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.250428 (+  2882us) negotiation.cc:107] Waiting for socket to connect
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.250497 (+    69us) client_negotiation.cc:174] Beginning negotiation
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.255772 (+  5275us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.256654 (+   882us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.256678 (+    24us) client_negotiation.cc:394] Received NEGOTIATE response from server
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.257482 (+   804us) client_negotiation.cc:189] Negotiated authn=SASL
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.258345 (+   863us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.258387 (+    42us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.260004 (+  1617us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.260024 (+    20us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.261669 (+  1645us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.261711 (+    42us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.262849 (+  1138us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
17:13:34.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.262886 (+    37us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.263279 (+   393us) 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
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.264220 (+   941us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.264288 (+    68us) client_negotiation.cc:656] calling sasl_client_start()
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.267682 (+  3394us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.272718 (+  5036us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.272741 (+    23us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.272766 (+    25us) client_negotiation.cc:760] calling sasl_client_step()
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.273490 (+   724us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274262 (+   772us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274280 (+    18us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274296 (+    16us) client_negotiation.cc:760] calling sasl_client_step()
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.274583 (+   287us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.275777 (+  1194us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.275808 (+    31us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.276312 (+   504us) client_negotiation.cc:769] Sending connection context
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.276692 (+   380us) client_negotiation.cc:240] Negotiation successful
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 17:13:34.277079 (+   387us) negotiation.cc:327] Negotiation complete: OK
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2349,"thread_start_us":1591,"threads_started":1}
17:13:34.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.281548 18719 heartbeater.cc:347] Connected to a master server at 127.17.197.190:39309
17:13:34.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.281935 18719 heartbeater.cc:464] Registering TS with master...
17:13:34.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.282997 18719 heartbeater.cc:511] Master 127.17.197.190:39309 requested a full tablet report, sending...
17:13:34.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.285604 18256 ts_manager.cc:194] Registered new tserver with Master: ce5058ec90604829adfaffc21265fd7a (127.17.197.131:40157)
17:13:34.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.287106 18256 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.17.197.131@KRBTEST.COM'} at 127.17.197.131:60807
17:13:34.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.300132 18198 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
17:13:34.306 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
17:13:34.308 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

17:13:34.318 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

17:13:34.319 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

17:13:34.328 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.17.197.190"
      port: 39309
    }
    bound_http_address {
      host: "127.17.197.190"
      port: 45403
    }
  }
}

17:13:34.332 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

17:13:34.335 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.17.197.129"
      port: 40971
    }
    bound_http_address {
      host: "127.17.197.129"
      port: 37895
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.17.197.130"
      port: 44523
    }
    bound_http_address {
      host: "127.17.197.130"
      port: 39171
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.17.197.131"
      port: 40157
    }
    bound_http_address {
      host: "127.17.197.131"
      port: 44473
    }
  }
}

17:13:34.335 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
17:13:34.551 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskjxDx7f/test-tmp/mini-kudu-cluster732468077731652265/krb5kdc/krb5cc
17:13:34.561 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
17:13:34.575 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
17:13:34.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.578194 18198 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu with pid 18317
17:13:34.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.596506 18198 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu with pid 18453
17:13:34.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:34.596369 18240 connection.cc:535] server connection from 127.17.197.129:47957 recv error: Network error: failed to read from TLS socket (remote: 127.17.197.129:47957): Connection reset by peer (error 104)
17:13:34.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:34.615228 18242 connection.cc:535] server connection from 127.17.197.130:51511 recv error: Network error: failed to read from TLS socket (remote: 127.17.197.130:51511): Connection reset by peer (error 104)
17:13:34.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.615514 18198 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu with pid 18589
17:13:34.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:34.632124 18243 connection.cc:535] server connection from 127.17.197.131:60807 recv error: Network error: failed to read from TLS socket (remote: 127.17.197.131:60807): Connection reset by peer (error 104)
17:13:34.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 17:13:34.632344 18198 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjxDx7f/build/tsan/bin/kudu with pid 18222
17:13:35.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 17:13:34.655149 18207 connection.cc:535] client connection to 127.17.197.190:39309 recv error: Network error: failed to read from TLS socket (remote: 127.17.197.190:39309): Connection reset by peer (error 104)
17:13:35.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T17:13:35Z chronyd exiting

Time: 17.025

OK (1 test)

