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

23:12:24.264 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
23:12:24.265 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

23:12:24.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.267897 28385 mini_oidc.cc:152] Starting JWKS server
23:12:24.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.271148 28385 webserver.cc:460] Webserver started at http://127.0.0.1:44159/ using document root <none> and password file <none>
23:12:24.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.271524 28385 mini_oidc.cc:189] Starting OIDC Discovery server
23:12:24.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.273110 28385 webserver.cc:460] Webserver started at http://127.0.0.1:38957/ using document root <none> and password file <none>
23:12:24.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:12:24Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
23:12:24.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:12:24Z Disabled control of system clock
23:12:24.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.315471 28385 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/wal
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/data
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/logs
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/data/info.pb
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:12:24.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.184.126:36183
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.184.126
23:12:24.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.184.84:42925
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.27.184.126:36183
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44159/jwks.json
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
23:12:24.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
23:12:24.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.589955 28398 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
23:12:24.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.590591 28398 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:12:24.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.591269 28398 flags.cc:425] Enabled unsafe flag: --never_fsync=true
23:12:24.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.620483 28398 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
23:12:24.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.620810 28398 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
23:12:24.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.621135 28398 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
23:12:24.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.621465 28398 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
23:12:24.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.621758 28398 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
23:12:24.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.621976 28398 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:44159/jwks.json
23:12:24.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.654935 28398 master_runner.cc:386] Master server non-default flags:
23:12:24.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:24.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.184.84:42925
23:12:24.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/data
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/wal
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.27.184.126:36183
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:24.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:12:24.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.184.126:36183
23:12:24.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:12:24.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:12:24.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:12:24.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44159/jwks.json
23:12:24.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
23:12:24.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/data/info.pb
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.184.126
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/logs
23:12:24.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 22:15:51 UTC on ccycloud.quasar-bszuid.root.comops.site
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492329
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.656379 28398 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:12:24.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:24.658527 28398 file_cache.cc:493] Constructed file cache file cache with capacity 419430
23:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.675766 28405 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
23:12:25.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.676610 28404 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
23:12:25.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:24.675833 28407 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
23:12:25.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:25.761224 28406 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
23:12:25.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.761497 28398 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
23:12:25.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.765453 28398 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
23:12:25.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:25.765672 28398 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
23:12:25.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.765812 28398 hybrid_clock.cc:648] HybridClock initialized: now 1783465945765791 us; error 0 us; skew 500 ppm
23:12:25.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.766592 28398 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:12:25.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.773766 28398 webserver.cc:460] Webserver started at http://127.27.184.126:35893/ using document root <none> and password file <none>
23:12:25.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.774611 28398 fs_manager.cc:359] Metadata directory not provided
23:12:25.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.774791 28398 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:12:25.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.775216 28398 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
23:12:25.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.779889 28398 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/data/instance:
23:12:25.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a44806fb2c784985a589e9fb102611ef"
23:12:25.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:25 on dist-test-slave-qgzj"
23:12:25.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.781136 28398 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/wal/instance:
23:12:25.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a44806fb2c784985a589e9fb102611ef"
23:12:25.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:25 on dist-test-slave-qgzj"
23:12:25.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.788004 28398 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.006s	sys 0.001s
23:12:25.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.793483 28413 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:12:25.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.794492 28398 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.002s
23:12:25.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.794826 28398 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/data,/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/wal
23:12:25.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a44806fb2c784985a589e9fb102611ef"
23:12:25.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:25 on dist-test-slave-qgzj"
23:12:25.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:25.795181 28398 fs_report.cc:383] FS layout report
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/wal
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/wal
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster6859093208734901553/master-0/data/data
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:12:25.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:12:25.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:12:25.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:12:25.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:12:25.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:12:25.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:12:25.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:12:25.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:12:25.846 [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
23:12:26.874 [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-taskKbRMX6/build/tsan/bin/kudu: process exited with non-zero status 1"
}

23:12:27.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:12:27Z chronyd exiting
23:12:28.953 [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-taskKbRMX6/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]
23:12:28.963 [INFO - main] (RandomUtils.java:49) Using random seed: 1783465948963
23:12:28.976 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
23:12:29.023 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
23:12:29.023 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskKbRMX6/test-tmp
23:12:29.024 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
23:12:29.024 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskKbRMX6/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
23:12:29.026 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329"
  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 {
  }
}

23:12:29.326 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
23:12:29.326 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

23:12:29.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:29.329371 28416 mini_oidc.cc:152] Starting JWKS server
23:12:29.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:29.332720 28416 webserver.cc:460] Webserver started at http://127.0.0.1:33733/ using document root <none> and password file <none>
23:12:29.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:29.333137 28416 mini_oidc.cc:189] Starting OIDC Discovery server
23:12:29.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:29.334725 28416 webserver.cc:460] Webserver started at http://127.0.0.1:32995/ using document root <none> and password file <none>
23:12:29.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
23:12:29.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/principal' for realm 'KRBTEST.COM',
23:12:29.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
23:12:29.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:29 dist-test-slave-qgzj krb5kdc[28427](info): setting up network...
23:12:29.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
23:12:29.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:29 dist-test-slave-qgzj krb5kdc[28427](info): set up 2 sockets
23:12:29.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:29 dist-test-slave-qgzj krb5kdc[28427](info): commencing operation
23:12:29.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
23:12:30.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.317075 28416 mini_kdc.cc:121] Time spent starting KDC: real 0.957s	user 0.004s	sys 0.015s
23:12:30.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
23:12:30.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
23:12:30.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
23:12:30.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
23:12:30.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
23:12:30.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
23:12:30.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
23:12:30.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
23:12:30.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
23:12:30.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
23:12:30.411 [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-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/test-user.keytab.
23:12:30.411 [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-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/test-user.keytab.
23:12:30.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:30 dist-test-slave-qgzj krb5kdc[28427](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465950, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
23:12:30.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
23:12:30.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:12:30Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
23:12:30.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:12:30Z Disabled control of system clock
23:12:30.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.192.62@KRBTEST.COM; defaulting to no policy
23:12:30.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:30.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.192.62@KRBTEST.COM" created.
23:12:30.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:30.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.62.keytab.
23:12:30.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.62.keytab.
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:30.481768 28416 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/wal
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:12:30.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:30.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:30.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:30.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/logs
23:12:30.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data/info.pb
23:12:30.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:30.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.62:42299
23:12:30.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.62
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.62.keytab
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.62
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.27.192.62:42299
23:12:30.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:12:30.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33733/jwks.json
23:12:30.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
23:12:30.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
23:12:30.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
23:12:30.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.756922 28440 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
23:12:30.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.757477 28440 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:12:30.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.758069 28440 flags.cc:425] Enabled unsafe flag: --never_fsync=true
23:12:30.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.788467 28440 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
23:12:30.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.788810 28440 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
23:12:30.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.789037 28440 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
23:12:30.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.789240 28440 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
23:12:30.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.789422 28440 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
23:12:30.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.789598 28440 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
23:12:30.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.789795 28440 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:33733/jwks.json
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:30.824760 28440 master_runner.cc:386] Master server non-default flags:
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/wal
23:12:30.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.27.192.62:42299
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.62.keytab
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.62
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.62:42299
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:12:30.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33733/jwks.json
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data/info.pb
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.62
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:12:30.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/logs
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 22:15:51 UTC on ccycloud.quasar-bszuid.root.comops.site
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492329
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:30.825809 28440 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:12:30.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:30.827072 28440 file_cache.cc:493] Constructed file cache file cache with capacity 419430
23:12:30.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.843730 28447 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
23:12:31.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.843768 28446 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
23:12:31.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:30.843806 28449 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
23:12:31.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:30.844805 28440 server_base.cc:1034] running on GCE node
23:12:31.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.934300 28440 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:12:31.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.937238 28440 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:12:31.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.938637 28440 hybrid_clock.cc:648] HybridClock initialized: now 1783465951938599 us; error 57 us; skew 500 ppm
23:12:31.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.939378 28440 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:12:31.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:31 dist-test-slave-qgzj krb5kdc[28427](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465951, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.192.62@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
23:12:31.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.945026 28440 init.cc:377] Logged in from keytab as kudu/127.27.192.62@KRBTEST.COM (short username kudu)
23:12:31.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.950560 28440 webserver.cc:460] Webserver started at http://127.27.192.62:44369/ using document root <none> and password file <none>
23:12:31.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.951694 28440 fs_manager.cc:359] Metadata directory not provided
23:12:31.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.951942 28440 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:12:31.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.952489 28440 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
23:12:31.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.957953 28440 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data/instance:
23:12:31.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d125610c65848a78d1338bb3b54faaf"
23:12:31.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:31 on dist-test-slave-qgzj"
23:12:31.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.958968 28440 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/wal/instance:
23:12:31.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d125610c65848a78d1338bb3b54faaf"
23:12:31.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:31 on dist-test-slave-qgzj"
23:12:31.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.965778 28440 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.008s	sys 0.000s
23:12:31.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.970986 28457 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:12:31.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.972018 28440 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.000s
23:12:31.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.972323 28440 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data,/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/wal
23:12:31.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d125610c65848a78d1338bb3b54faaf"
23:12:31.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:31 on dist-test-slave-qgzj"
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:31.972704 28440 fs_report.cc:383] FS layout report
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/wal
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/wal
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data/data
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:12:31.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:12:31.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:12:31.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:12:31.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:12:32.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.003671 28440 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
23:12:32.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.009295 28440 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
23:12:32.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.009698 28440 kserver.cc:163] Server-wide thread pool size limit: 3276
23:12:32.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.074600 28440 rpc_server.cc:307] RPC server started. Bound to: 127.27.192.62:42299
23:12:32.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.074678 28508 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.192.62:42299 every 8 connection(s)
23:12:32.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.077299 28440 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/data/info.pb
23:12:32.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.082240 28509 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
23:12:32.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.084985 28416 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu as pid 28440
23:12:32.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.085446 28416 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/master-0/wal/instance
23:12:32.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.099730 28509 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf: Bootstrap starting.
23:12:32.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.106547 28509 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf: Neither blocks nor log segments found. Creating new log.
23:12:32.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.108171 28509 log.cc:824] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf: Log is configured to *not* fsync() on all Append() calls
23:12:32.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.113072 28509 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf: No bootstrap required, opened a new log
23:12:32.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:32 dist-test-slave-qgzj krb5kdc[28427](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465950, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.27.192.62@KRBTEST.COM
23:12:32.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.134459 28511 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.090245 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:35044 (local address 127.27.192.62:42299)
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.092823 (+  2578us) server_negotiation.cc:206] Beginning negotiation
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.092857 (+    34us) server_negotiation.cc:399] Waiting for connection header
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.094060 (+  1203us) server_negotiation.cc:407] Connection header received
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.105039 (+ 10979us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.105088 (+    49us) server_negotiation.cc:461] Received NEGOTIATE request from client
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.105529 (+   441us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.105938 (+   409us) server_negotiation.cc:226] Negotiated authn=SASL
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.110173 (+  4235us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.112243 (+  2070us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.114673 (+  2430us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.115552 (+   879us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.115861 (+   309us) 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
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.122598 (+  6737us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.122622 (+    24us) server_negotiation.cc:881] Received SASL_INITIATE request from client
23:12:32.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.122651 (+    29us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
23:12:32.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.122757 (+   106us) server_negotiation.cc:922] calling sasl_server_start()
23:12:32.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.126716 (+  3959us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:32.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.128120 (+  1404us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:32.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.128143 (+    23us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:32.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.128164 (+    21us) server_negotiation.cc:967] calling sasl_server_step()
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.128425 (+   261us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.129372 (+   947us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.129393 (+    21us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.129415 (+    22us) server_negotiation.cc:967] calling sasl_server_step()
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.130120 (+   705us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.130629 (+   509us) server_negotiation.cc:1035] Waiting for connection context
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.131792 (+  1163us) server_negotiation.cc:299] Negotiation successful
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:32.132255 (+   463us) negotiation.cc:327] Negotiation complete: OK
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":2080,"thread_start_us":1632,"threads_started":1}
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.135833 28509 raft_consensus.cc:348] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0d125610c65848a78d1338bb3b54faaf" member_type: VOTER last_known_addr { host: "127.27.192.62" port: 42299 } }
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.136663 28509 raft_consensus.cc:374] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:12:32.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.136868 28509 raft_consensus.cc:729] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0d125610c65848a78d1338bb3b54faaf, State: Initialized, Role: FOLLOWER
23:12:32.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.137526 28509 consensus_queue.cc:260] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [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: "0d125610c65848a78d1338bb3b54faaf" member_type: VOTER last_known_addr { host: "127.27.192.62" port: 42299 } }
23:12:32.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.138191 28509 raft_consensus.cc:388] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
23:12:32.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.138513 28509 raft_consensus.cc:482] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
23:12:32.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.138895 28509 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 0 FOLLOWER]: Advancing to term 1
23:12:32.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.143373 28509 raft_consensus.cc:504] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0d125610c65848a78d1338bb3b54faaf" member_type: VOTER last_known_addr { host: "127.27.192.62" port: 42299 } }
23:12:32.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.144124 28509 leader_election.cc:302] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [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: 0d125610c65848a78d1338bb3b54faaf; no voters: 
23:12:32.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.146143 28509 leader_election.cc:288] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [CANDIDATE]: Term 1 election: Requested vote from peers 
23:12:32.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.146530 28514 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 1 FOLLOWER]: Leader election won for term 1
23:12:32.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.151724 28514 raft_consensus.cc:686] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [term 1 LEADER]: Becoming Leader. State: Replica: 0d125610c65848a78d1338bb3b54faaf, State: Running, Role: LEADER
23:12:32.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.152736 28514 consensus_queue.cc:237] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [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: "0d125610c65848a78d1338bb3b54faaf" member_type: VOTER last_known_addr { host: "127.27.192.62" port: 42299 } }
23:12:32.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.153240 28509 sys_catalog.cc:564] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [sys.catalog]: configured and running, proceeding with master startup.
23:12:32.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.162573 28515 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "0d125610c65848a78d1338bb3b54faaf" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0d125610c65848a78d1338bb3b54faaf" member_type: VOTER last_known_addr { host: "127.27.192.62" port: 42299 } } }
23:12:32.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.163290 28515 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [sys.catalog]: This master's current role is: LEADER
23:12:32.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.164569 28516 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [sys.catalog]: SysCatalogTable state changed. Reason: New leader 0d125610c65848a78d1338bb3b54faaf. Latest consensus state: current_term: 1 leader_uuid: "0d125610c65848a78d1338bb3b54faaf" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "0d125610c65848a78d1338bb3b54faaf" member_type: VOTER last_known_addr { host: "127.27.192.62" port: 42299 } } }
23:12:32.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.165210 28516 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf [sys.catalog]: This master's current role is: LEADER
23:12:32.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.167369 28524 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
23:12:32.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.177961 28524 catalog_manager.cc:1484] Initializing Kudu cluster ID...
23:12:32.195 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.195164 28524 catalog_manager.cc:1347] Generated new cluster ID: 3525001755af4556a75a5578bf504273
23:12:32.195 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.195405 28524 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
23:12:32.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.264264 28524 catalog_manager.cc:1370] Generated new certificate authority record
23:12:32.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.266621 28524 catalog_manager.cc:1504] Loading token signing keys...
23:12:32.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.278087 28524 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 0d125610c65848a78d1338bb3b54faaf: Generated new TSK 0
23:12:32.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.278986 28524 catalog_manager.cc:1514] Initializing in-progress tserver states...
23:12:32.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.192.1@KRBTEST.COM; defaulting to no policy
23:12:32.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:32.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.192.1@KRBTEST.COM" created.
23:12:32.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:32.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.1.keytab.
23:12:32.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.1.keytab.
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.322945 28416 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/wal
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/logs
23:12:32.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data/info.pb
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.1:0
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.192.1
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.1
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.192.62:42299
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.1.keytab
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.1
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:32.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:32.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
23:12:32.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
23:12:32.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
23:12:32.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.617969 28535 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
23:12:32.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.618434 28535 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:12:32.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.619063 28535 flags.cc:425] Enabled unsafe flag: --never_fsync=true
23:12:32.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.649605 28535 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
23:12:32.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.649902 28535 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
23:12:32.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.650768 28535 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.192.1
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.686399 28535 tablet_server_runner.cc:78] Tablet server non-default flags:
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/wal
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.1.keytab
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.1
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:32.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.1:0
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data/info.pb
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.1
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.192.62:42299
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:32.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.192.1
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/logs
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 22:15:51 UTC on ccycloud.quasar-bszuid.root.comops.site
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492329
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.687750 28535 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:12:32.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.689113 28535 file_cache.cc:493] Constructed file cache file cache with capacity 419430
23:12:33.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.710022 28542 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
23:12:33.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.710050 28541 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
23:12:33.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:32.712107 28535 server_base.cc:1034] running on GCE node
23:12:33.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:32.710016 28544 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
23:12:33.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.823859 28535 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:12:33.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.826701 28535 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:12:33.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.828358 28535 hybrid_clock.cc:648] HybridClock initialized: now 1783465953828317 us; error 39 us; skew 500 ppm
23:12:33.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.829418 28535 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:12:33.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:33 dist-test-slave-qgzj krb5kdc[28427](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465953, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.192.1@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
23:12:33.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.836329 28535 init.cc:377] Logged in from keytab as kudu/127.27.192.1@KRBTEST.COM (short username kudu)
23:12:33.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.841935 28535 webserver.cc:460] Webserver started at http://127.27.192.1:45497/ using document root <none> and password file <none>
23:12:33.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.843044 28535 fs_manager.cc:359] Metadata directory not provided
23:12:33.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.843290 28535 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:12:33.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.843789 28535 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
23:12:33.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.850694 28535 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data/instance:
23:12:33.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eea65efd926d48438fbf8afddb9e833e"
23:12:33.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:33 on dist-test-slave-qgzj"
23:12:33.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.852279 28535 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/wal/instance:
23:12:33.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eea65efd926d48438fbf8afddb9e833e"
23:12:33.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:33 on dist-test-slave-qgzj"
23:12:33.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.861299 28535 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.007s	sys 0.001s
23:12:33.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.867975 28552 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:12:33.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.869081 28535 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.001s
23:12:33.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.869401 28535 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data,/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/wal
23:12:33.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eea65efd926d48438fbf8afddb9e833e"
23:12:33.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:33 on dist-test-slave-qgzj"
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.869737 28535 fs_report.cc:383] FS layout report
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/wal
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/wal
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data/data
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:12:33.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:12:33.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.902062 28535 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
23:12:33.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.907847 28535 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
23:12:33.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.908262 28535 kserver.cc:163] Server-wide thread pool size limit: 3276
23:12:33.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.910797 28535 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
23:12:33.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.914773 28535 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:12:33.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.914961 28535 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:12:33.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.915149 28535 ts_tablet_manager.cc:610] Registered 0 tablets
23:12:33.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:33.915326 28535 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
23:12:34.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.046433 28535 rpc_server.cc:307] RPC server started. Bound to: 127.27.192.1:45503
23:12:34.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.046540 28664 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.192.1:45503 every 8 connection(s)
23:12:34.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.049050 28535 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/data/info.pb
23:12:34.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.052461 28416 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu as pid 28535
23:12:34.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.052954 28416 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-0/wal/instance
23:12:34.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.192.2@KRBTEST.COM; defaulting to no policy
23:12:34.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:34.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.192.2@KRBTEST.COM" created.
23:12:34.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:34 dist-test-slave-qgzj krb5kdc[28427](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465953, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.192.1@KRBTEST.COM for kudu/127.27.192.62@KRBTEST.COM
23:12:34.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.090483 28667 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.053520 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.27.192.1:48499 (local address 127.27.192.62:42299)
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.054685 (+  1165us) server_negotiation.cc:206] Beginning negotiation
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.054712 (+    27us) server_negotiation.cc:399] Waiting for connection header
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.056920 (+  2208us) server_negotiation.cc:407] Connection header received
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.061598 (+  4678us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.061626 (+    28us) server_negotiation.cc:461] Received NEGOTIATE request from client
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.061790 (+   164us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.061930 (+   140us) server_negotiation.cc:226] Negotiated authn=SASL
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.064156 (+  2226us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.065197 (+  1041us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.067204 (+  2007us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.067786 (+   582us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.068035 (+   249us) 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
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.077776 (+  9741us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.077797 (+    21us) server_negotiation.cc:881] Received SASL_INITIATE request from client
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.077815 (+    18us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
23:12:34.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.077910 (+    95us) server_negotiation.cc:922] calling sasl_server_start()
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.082609 (+  4699us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.084472 (+  1863us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.084499 (+    27us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.084520 (+    21us) server_negotiation.cc:967] calling sasl_server_step()
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.084803 (+   283us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.085805 (+  1002us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.085835 (+    30us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.085858 (+    23us) server_negotiation.cc:967] calling sasl_server_step()
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.086557 (+   699us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.086869 (+   312us) server_negotiation.cc:1035] Waiting for connection context
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.088291 (+  1422us) server_negotiation.cc:299] Negotiation successful
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.088703 (+   412us) negotiation.cc:327] Negotiation complete: OK
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":779,"thread_start_us":534,"threads_started":1}
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.091267 28668 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
23:12:34.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.053913 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.27.192.62:42299 (local address 127.27.192.1:48499)
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.056694 (+  2781us) negotiation.cc:107] Waiting for socket to connect
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.056782 (+    88us) client_negotiation.cc:174] Beginning negotiation
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.061279 (+  4497us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.062233 (+   954us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.062268 (+    35us) client_negotiation.cc:394] Received NEGOTIATE response from server
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.063045 (+   777us) client_negotiation.cc:189] Negotiated authn=SASL
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.063905 (+   860us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.063945 (+    40us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.065417 (+  1472us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.065438 (+    21us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.066974 (+  1536us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.067012 (+    38us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.068114 (+  1102us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
23:12:34.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.068148 (+    34us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.068622 (+   474us) 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
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.069561 (+   939us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.069622 (+    61us) client_negotiation.cc:656] calling sasl_client_start()
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.077322 (+  7700us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.083032 (+  5710us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.083064 (+    32us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.083099 (+    35us) client_negotiation.cc:760] calling sasl_client_step()
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.084127 (+  1028us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.085085 (+   958us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.085113 (+    28us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
23:12:34.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.085134 (+    21us) client_negotiation.cc:760] calling sasl_client_step()
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.085474 (+   340us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.086881 (+  1407us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.086920 (+    39us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.087492 (+   572us) client_negotiation.cc:769] Sending connection context
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.087981 (+   489us) client_negotiation.cc:240] Negotiation successful
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:34.088439 (+   458us) negotiation.cc:327] Negotiation complete: OK
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2218,"thread_start_us":1520,"threads_started":1}
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.093713 28665 heartbeater.cc:347] Connected to a master server at 127.27.192.62:42299
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.094146 28665 heartbeater.cc:464] Registering TS with master...
23:12:34.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.095315 28665 heartbeater.cc:511] Master 127.27.192.62:42299 requested a full tablet report, sending...
23:12:34.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.098603 28474 ts_manager.cc:194] Registered new tserver with Master: eea65efd926d48438fbf8afddb9e833e (127.27.192.1:45503)
23:12:34.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:34.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.2.keytab.
23:12:34.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.2.keytab.
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.100844 28416 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/wal
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/logs
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data/info.pb
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:12:34.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.2:0
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.192.2
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.2
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.192.62:42299
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.2.keytab
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.2
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:34.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
23:12:34.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.101478 28474 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.27.192.1@KRBTEST.COM'} at 127.27.192.1:48499
23:12:34.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.388989 28671 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
23:12:34.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.389421 28671 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:12:34.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.390017 28671 flags.cc:425] Enabled unsafe flag: --never_fsync=true
23:12:34.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.419428 28671 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
23:12:34.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.419728 28671 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
23:12:34.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.420576 28671 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.192.2
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.454109 28671 tablet_server_runner.cc:78] Tablet server non-default flags:
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/wal
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.2.keytab
23:12:34.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.2
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.2:0
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data/info.pb
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.2
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.192.62:42299
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.192.2
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/logs
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 22:15:51 UTC on ccycloud.quasar-bszuid.root.comops.site
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492329
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:12:34.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.455365 28671 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:12:34.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.456439 28671 file_cache.cc:493] Constructed file cache file cache with capacity 419430
23:12:34.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.473909 28678 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
23:12:35.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.106501 28665 heartbeater.cc:503] Master 127.27.192.62:42299 was elected leader, sending a full tablet report...
23:12:35.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.474638 28677 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
23:12:35.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:34.476766 28680 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
23:12:35.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:34.475772 28671 server_base.cc:1034] running on GCE node
23:12:35.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.553182 28671 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:12:35.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.555516 28671 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:12:35.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.556927 28671 hybrid_clock.cc:648] HybridClock initialized: now 1783465955556864 us; error 71 us; skew 500 ppm
23:12:35.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.557739 28671 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:12:35.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:35 dist-test-slave-qgzj krb5kdc[28427](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465955, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.192.2@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
23:12:35.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.563186 28671 init.cc:377] Logged in from keytab as kudu/127.27.192.2@KRBTEST.COM (short username kudu)
23:12:35.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.567999 28671 webserver.cc:460] Webserver started at http://127.27.192.2:45947/ using document root <none> and password file <none>
23:12:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.568943 28671 fs_manager.cc:359] Metadata directory not provided
23:12:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.569144 28671 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:12:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.569653 28671 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
23:12:35.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.574226 28671 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data/instance:
23:12:35.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5a68b1f4e8394ef78389b2ace746648e"
23:12:35.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:35 on dist-test-slave-qgzj"
23:12:35.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.575314 28671 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/wal/instance:
23:12:35.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5a68b1f4e8394ef78389b2ace746648e"
23:12:35.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:35 on dist-test-slave-qgzj"
23:12:35.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.582053 28671 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.004s	sys 0.004s
23:12:35.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.587220 28688 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:12:35.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.588248 28671 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.000s
23:12:35.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.588620 28671 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data,/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/wal
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5a68b1f4e8394ef78389b2ace746648e"
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:35 on dist-test-slave-qgzj"
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.588933 28671 fs_report.cc:383] FS layout report
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/wal
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/wal
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data/data
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:12:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:12:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:12:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:12:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:12:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.633507 28671 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
23:12:35.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.638674 28671 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
23:12:35.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.639163 28671 kserver.cc:163] Server-wide thread pool size limit: 3276
23:12:35.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.641692 28671 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
23:12:35.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.645507 28671 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:12:35.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.645712 28671 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:12:35.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.645980 28671 ts_tablet_manager.cc:610] Registered 0 tablets
23:12:35.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.646189 28671 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
23:12:35.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.776762 28671 rpc_server.cc:307] RPC server started. Bound to: 127.27.192.2:43313
23:12:35.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.776875 28800 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.192.2:43313 every 8 connection(s)
23:12:35.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.779309 28671 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/data/info.pb
23:12:35.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.785526 28416 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu as pid 28671
23:12:35.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.785985 28416 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-1/wal/instance
23:12:35.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.27.192.3@KRBTEST.COM; defaulting to no policy
23:12:35.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:35.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.27.192.3@KRBTEST.COM" created.
23:12:35.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:35 dist-test-slave-qgzj krb5kdc[28427](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465955, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.192.2@KRBTEST.COM for kudu/127.27.192.62@KRBTEST.COM
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.821111 28804 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.786019 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.27.192.62:42299 (local address 127.27.192.2:35067)
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.789684 (+  3665us) negotiation.cc:107] Waiting for socket to connect
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.789769 (+    85us) client_negotiation.cc:174] Beginning negotiation
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.794463 (+  4694us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.795441 (+   978us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.795473 (+    32us) client_negotiation.cc:394] Received NEGOTIATE response from server
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.796234 (+   761us) client_negotiation.cc:189] Negotiated authn=SASL
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.797328 (+  1094us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.797392 (+    64us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
23:12:35.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.799286 (+  1894us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.799306 (+    20us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.800866 (+  1560us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.800903 (+    37us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.802207 (+  1304us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.802230 (+    23us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.802607 (+   377us) 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
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.803555 (+   948us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.803626 (+    71us) client_negotiation.cc:656] calling sasl_client_start()
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.810538 (+  6912us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.814940 (+  4402us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.814964 (+    24us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.814987 (+    23us) client_negotiation.cc:760] calling sasl_client_step()
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.815628 (+   641us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.816486 (+   858us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.816506 (+    20us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.816521 (+    15us) client_negotiation.cc:760] calling sasl_client_step()
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.816826 (+   305us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.818012 (+  1186us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.818041 (+    29us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.818438 (+   397us) client_negotiation.cc:769] Sending connection context
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.818788 (+   350us) client_negotiation.cc:240] Negotiation successful
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.819186 (+   398us) negotiation.cc:327] Negotiation complete: OK
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":3126,"thread_start_us":2381,"threads_started":1}
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.821600 28803 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.786652 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.27.192.2:35067 (local address 127.27.192.62:42299)
23:12:35.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.790369 (+  3717us) server_negotiation.cc:206] Beginning negotiation
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.790398 (+    29us) server_negotiation.cc:399] Waiting for connection header
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.790448 (+    50us) server_negotiation.cc:407] Connection header received
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.794802 (+  4354us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.794831 (+    29us) server_negotiation.cc:461] Received NEGOTIATE request from client
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.795003 (+   172us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.795144 (+   141us) server_negotiation.cc:226] Negotiated authn=SASL
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.797637 (+  2493us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.799062 (+  1425us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.801090 (+  2028us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.801946 (+   856us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.802206 (+   260us) 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
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.811027 (+  8821us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.811053 (+    26us) server_negotiation.cc:881] Received SASL_INITIATE request from client
23:12:35.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.811073 (+    20us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.811172 (+    99us) server_negotiation.cc:922] calling sasl_server_start()
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.814654 (+  3482us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.815922 (+  1268us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.815948 (+    26us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.815967 (+    19us) server_negotiation.cc:967] calling sasl_server_step()
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.816201 (+   234us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.817103 (+   902us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.817128 (+    25us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.817147 (+    19us) server_negotiation.cc:967] calling sasl_server_step()
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.817781 (+   634us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.818061 (+   280us) server_negotiation.cc:1035] Waiting for connection context
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.819099 (+  1038us) server_negotiation.cc:299] Negotiation successful
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:35.819474 (+   375us) negotiation.cc:327] Negotiation complete: OK
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":3321,"thread_start_us":3056,"threads_started":1}
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.824143 28801 heartbeater.cc:347] Connected to a master server at 127.27.192.62:42299
23:12:35.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.824604 28801 heartbeater.cc:464] Registering TS with master...
23:12:35.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.825634 28801 heartbeater.cc:511] Master 127.27.192.62:42299 requested a full tablet report, sending...
23:12:35.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.828094 28474 ts_manager.cc:194] Registered new tserver with Master: 5a68b1f4e8394ef78389b2ace746648e (127.27.192.2:43313)
23:12:35.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
23:12:35.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.3.keytab.
23:12:35.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.27.192.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.3.keytab.
23:12:35.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.830354 28474 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.27.192.2@KRBTEST.COM'} at 127.27.192.2:35067
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:35.833151 28416 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/wal
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/logs
23:12:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data/info.pb
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.3:0
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.192.3
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.3
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.192.62:42299
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.3.keytab
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.3
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
23:12:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
23:12:36.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.116979 28807 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
23:12:36.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.117482 28807 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:12:36.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.118189 28807 flags.cc:425] Enabled unsafe flag: --never_fsync=true
23:12:36.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.147878 28807 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
23:12:36.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.148264 28807 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
23:12:36.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.149312 28807 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.27.192.3
23:12:36.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:36.183125 28807 tablet_server_runner.cc:78] Tablet server non-default flags:
23:12:36.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:12:36.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.27.192.20:41907
23:12:36.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:12:36.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:12:36.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data
23:12:36.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/wal
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kudu_127.27.192.3.keytab
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.27.192.3
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.27.192.3:0
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data/info.pb
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.27.192.3
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.27.192.62:42299
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.27.192.3
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/logs
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:12:36.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:12:36.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
23:12:36.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
23:12:36.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:12:36.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 22:15:51 UTC on ccycloud.quasar-bszuid.root.comops.site
23:12:36.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492329
23:12:36.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:12:36.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:36.184465 28807 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:12:36.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:36.185839 28807 file_cache.cc:493] Constructed file cache file cache with capacity 419430
23:12:36.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.204005 28814 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
23:12:36.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:36.833868 28801 heartbeater.cc:503] Master 127.27.192.62:42299 was elected leader, sending a full tablet report...
23:12:37.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.204016 28813 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
23:12:37.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:36.204888 28816 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
23:12:37.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:36.205559 28807 server_base.cc:1034] running on GCE node
23:12:37.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.285854 28807 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:12:37.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.288106 28807 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:12:37.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.289456 28807 hybrid_clock.cc:648] HybridClock initialized: now 1783465957289417 us; error 54 us; skew 500 ppm
23:12:37.290 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.290302 28807 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:12:37.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:37 dist-test-slave-qgzj krb5kdc[28427](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465957, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.192.3@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
23:12:37.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.295738 28807 init.cc:377] Logged in from keytab as kudu/127.27.192.3@KRBTEST.COM (short username kudu)
23:12:37.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.300457 28807 webserver.cc:460] Webserver started at http://127.27.192.3:35279/ using document root <none> and password file <none>
23:12:37.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.301309 28807 fs_manager.cc:359] Metadata directory not provided
23:12:37.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.301496 28807 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:12:37.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.301856 28807 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
23:12:37.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.306478 28807 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data/instance:
23:12:37.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "31ebb73d90a14d3784f8ed9e2256cca8"
23:12:37.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:37 on dist-test-slave-qgzj"
23:12:37.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.307451 28807 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/wal/instance:
23:12:37.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "31ebb73d90a14d3784f8ed9e2256cca8"
23:12:37.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:37 on dist-test-slave-qgzj"
23:12:37.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.314311 28807 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.004s	sys 0.004s
23:12:37.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.319585 28824 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:12:37.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.320684 28807 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.320974 28807 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data,/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/wal
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "31ebb73d90a14d3784f8ed9e2256cca8"
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:12:37 on dist-test-slave-qgzj"
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.321244 28807 fs_report.cc:383] FS layout report
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/wal
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/wal
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data/data
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:12:37.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:12:37.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:12:37.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:12:37.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:12:37.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:12:37.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:12:37.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:12:37.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:12:37.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.362902 28807 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
23:12:37.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.368290 28807 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
23:12:37.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.368712 28807 kserver.cc:163] Server-wide thread pool size limit: 3276
23:12:37.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.371266 28807 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
23:12:37.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.375058 28807 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:12:37.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.375253 28807 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:12:37.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.375443 28807 ts_tablet_manager.cc:610] Registered 0 tablets
23:12:37.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.375595 28807 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
23:12:37.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.517433 28807 rpc_server.cc:307] RPC server started. Bound to: 127.27.192.3:37659
23:12:37.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.517562 28936 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.27.192.3:37659 every 8 connection(s)
23:12:37.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.520522 28807 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/data/info.pb
23:12:37.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.526664 28416 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu as pid 28807
23:12:37.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.527150 28416 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/ts-2/wal/instance
23:12:37.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 23:12:37 dist-test-slave-qgzj krb5kdc[28427](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783465957, etypes {rep=17 tkt=17 ses=17}, kudu/127.27.192.3@KRBTEST.COM for kudu/127.27.192.62@KRBTEST.COM
23:12:37.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.554498 28939 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
23:12:37.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.525361 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.27.192.3:55309 (local address 127.27.192.62:42299)
23:12:37.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.526573 (+  1212us) server_negotiation.cc:206] Beginning negotiation
23:12:37.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.526600 (+    27us) server_negotiation.cc:399] Waiting for connection header
23:12:37.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.528663 (+  2063us) server_negotiation.cc:407] Connection header received
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.534077 (+  5414us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.534096 (+    19us) server_negotiation.cc:461] Received NEGOTIATE request from client
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.534250 (+   154us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.534385 (+   135us) server_negotiation.cc:226] Negotiated authn=SASL
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.536466 (+  2081us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.537639 (+  1173us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.539614 (+  1975us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.540203 (+   589us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.540384 (+   181us) 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
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.545161 (+  4777us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.545194 (+    33us) server_negotiation.cc:881] Received SASL_INITIATE request from client
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.545225 (+    31us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.545337 (+   112us) server_negotiation.cc:922] calling sasl_server_start()
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.549178 (+  3841us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550304 (+  1126us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550324 (+    20us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550337 (+    13us) server_negotiation.cc:967] calling sasl_server_step()
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550513 (+   176us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.551193 (+   680us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.551210 (+    17us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.551223 (+    13us) server_negotiation.cc:967] calling sasl_server_step()
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.551682 (+   459us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.551886 (+   204us) server_negotiation.cc:1035] Waiting for connection context
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.552829 (+   943us) server_negotiation.cc:299] Negotiation successful
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.553101 (+   272us) negotiation.cc:327] Negotiation complete: OK
23:12:37.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":792,"thread_start_us":524,"threads_started":1}
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.554912 28940 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.525719 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.27.192.62:42299 (local address 127.27.192.3:55309)
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.528392 (+  2673us) negotiation.cc:107] Waiting for socket to connect
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.528477 (+    85us) client_negotiation.cc:174] Beginning negotiation
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.533796 (+  5319us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.534650 (+   854us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.534674 (+    24us) client_negotiation.cc:394] Received NEGOTIATE response from server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.535415 (+   741us) client_negotiation.cc:189] Negotiated authn=SASL
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.536229 (+   814us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.536276 (+    47us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.537844 (+  1568us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.537864 (+    20us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.539402 (+  1538us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.539437 (+    35us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.540471 (+  1034us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.540495 (+    24us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.540841 (+   346us) 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
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.541637 (+   796us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.541701 (+    64us) client_negotiation.cc:656] calling sasl_client_start()
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.544704 (+  3003us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.549427 (+  4723us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.549448 (+    21us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.549470 (+    22us) client_negotiation.cc:760] calling sasl_client_step()
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550095 (+   625us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550726 (+   631us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550745 (+    19us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550758 (+    13us) client_negotiation.cc:760] calling sasl_client_step()
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.550997 (+   239us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.551881 (+   884us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.551908 (+    27us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
23:12:37.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.552285 (+   377us) client_negotiation.cc:769] Sending connection context
23:12:37.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.552649 (+   364us) client_negotiation.cc:240] Negotiation successful
23:12:37.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 23:12:37.552952 (+   303us) negotiation.cc:327] Negotiation complete: OK
23:12:37.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":2150,"thread_start_us":1414,"threads_started":1}
23:12:37.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.557279 28937 heartbeater.cc:347] Connected to a master server at 127.27.192.62:42299
23:12:37.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.557714 28937 heartbeater.cc:464] Registering TS with master...
23:12:37.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.558583 28937 heartbeater.cc:511] Master 127.27.192.62:42299 requested a full tablet report, sending...
23:12:37.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.560847 28473 ts_manager.cc:194] Registered new tserver with Master: 31ebb73d90a14d3784f8ed9e2256cca8 (127.27.192.3:37659)
23:12:37.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.562296 28473 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.27.192.3@KRBTEST.COM'} at 127.27.192.3:55309
23:12:37.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.574795 28416 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
23:12:37.581 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
23:12:37.582 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

23:12:37.591 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

23:12:37.592 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

23:12:37.600 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.27.192.62"
      port: 42299
    }
    bound_http_address {
      host: "127.27.192.62"
      port: 44369
    }
  }
}

23:12:37.605 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

23:12:37.609 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.27.192.1"
      port: 45503
    }
    bound_http_address {
      host: "127.27.192.1"
      port: 45497
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.27.192.2"
      port: 43313
    }
    bound_http_address {
      host: "127.27.192.2"
      port: 45947
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.27.192.3"
      port: 37659
    }
    bound_http_address {
      host: "127.27.192.3"
      port: 35279
    }
  }
}

23:12:37.609 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
23:12:37.866 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskKbRMX6/test-tmp/mini-kudu-cluster1869342051081474329/krb5kdc/krb5cc
23:12:37.880 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
23:12:37.892 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
23:12:37.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.894766 28416 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu with pid 28535
23:12:37.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.912647 28416 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu with pid 28671
23:12:37.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:37.912464 28460 connection.cc:535] server connection from 127.27.192.1:48499 recv error: Network error: failed to read from TLS socket (remote: 127.27.192.1:48499): Connection reset by peer (error 104)
23:12:37.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:37.929545 28458 connection.cc:535] server connection from 127.27.192.2:35067 recv error: Network error: failed to read from TLS socket (remote: 127.27.192.2:35067): Connection reset by peer (error 104)
23:12:37.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.929812 28416 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu with pid 28807
23:12:37.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:37.945708 28461 connection.cc:535] server connection from 127.27.192.3:55309 recv error: Network error: failed to read from TLS socket (remote: 127.27.192.3:55309): Connection reset by peer (error 104)
23:12:37.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:12:37.946005 28416 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskKbRMX6/build/tsan/bin/kudu with pid 28440
23:12:39.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:12:37.968803 28425 connection.cc:535] client connection to 127.27.192.62:42299 recv error: Network error: failed to read from TLS socket (remote: 127.27.192.62:42299): Connection reset by peer (error 104)
23:12:39.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:12:39Z chronyd exiting

Time: 16.788

OK (1 test)

