JUnit version 4.13.2
.16:04:36.798 [DEBUG - main] (TestNegotiationTLSv13.java:108) enabled TLS protocols: TLSv1.3 TLSv1.2 TLSv1.1 TLSv1
16:04:36.801 [DEBUG - main] (TestNegotiationTLSv13.java:110) supported TLS protocols: TLSv1.3 TLSv1.2 SSLv3 TLSv1.1 SSLv2Hello TLSv1
16:04:36.811 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskjEqVoj/test-tmp
16:04:36.814 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/release/bin
16:04:36.818 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskjEqVoj/java/build/dist-test/../../../build/release/bin/kudu, test, mini_cluster, --serialization=pb]
16:04:37.104 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 0
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848"
  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 {
  }
}

16:04:37.119 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
16:04:37.120 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

16:04:37.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.121467 29379 mini_oidc.cc:152] Starting JWKS server
16:04:37.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.122871 29379 webserver.cc:460] Webserver started at http://127.0.0.1:45389/ using document root <none> and password file <none>
16:04:37.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.123016 29379 mini_oidc.cc:189] Starting OIDC Discovery server
16:04:37.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.123227 29379 webserver.cc:460] Webserver started at http://127.0.0.1:37831/ using document root <none> and password file <none>
16:04:37.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T16:04:37Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
16:04:37.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T16:04:37Z Disabled control of system clock
16:04:37.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.141376 29379 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:37.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/wal
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/data
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/logs
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/data/info.pb
16:04:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:04:37.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.176.254:34793
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.176.254
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.176.212:42293
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.176.254:34793
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45389/jwks.json
16:04:37.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
16:04:37.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
16:04:37.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.237344 29391 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:04:37.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.237587 29391 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:04:37.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.237612 29391 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:04:37.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.239158 29391 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
16:04:37.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.239221 29391 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:04:37.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.239238 29391 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
16:04:37.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.239252 29391 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
16:04:37.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.239264 29391 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
16:04:37.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.239275 29391 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:45389/jwks.json
16:04:37.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.241137 29391 master_runner.cc:386] Master server non-default flags:
16:04:37.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:37.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.176.212:42293
16:04:37.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:37.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
16:04:37.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/data
16:04:37.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/wal
16:04:37.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:04:37.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.176.254:34793
16:04:37.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:37.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:37.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.176.254:34793
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45389/jwks.json
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:37.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/data/info.pb
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.176.254
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29391
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/logs
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:04:37.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:04:37.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
16:04:37.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:04:37.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:04:37.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
16:04:37.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 14:54:46 UTC on ccycloud.quasar-lejogm.root.comops.site
16:04:37.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493842
16:04:37.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.241410 29391 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:04:37.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.241684 29391 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:04:37.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.247781 29397 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
16:04:37.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.247774 29399 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
16:04:37.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.247771 29396 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
16:04:37.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.248247 29391 server_base.cc:1034] running on GCE node
16:04:37.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.248484 29391 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
16:04:37.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:37.248520 29391 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
16:04:37.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.248544 29391 hybrid_clock.cc:648] HybridClock initialized: now 1783526677248544 us; error 0 us; skew 500 ppm
16:04:37.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.249963 29391 webserver.cc:460] Webserver started at http://127.28.176.254:38399/ using document root <none> and password file <none>
16:04:37.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.250186 29391 fs_manager.cc:359] Metadata directory not provided
16:04:37.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.250222 29391 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:04:37.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.250329 29391 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:04:37.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.251494 29391 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/data/instance:
16:04:37.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "10b507c9092b4b94b53137babdc66c7f"
16:04:37.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:37 on dist-test-slave-k4wn"
16:04:37.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.251799 29391 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/wal/instance:
16:04:37.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "10b507c9092b4b94b53137babdc66c7f"
16:04:37.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:37 on dist-test-slave-k4wn"
16:04:37.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.253217 29391 fs_manager.cc:691] Time spent creating directory manager: real 0.001s	user 0.000s	sys 0.001s
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.254006 29404 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.254158 29391 fs_manager.cc:725] Time spent opening block manager: real 0.000s	user 0.000s	sys 0.001s
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.254226 29391 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/data,/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/wal
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "10b507c9092b4b94b53137babdc66c7f"
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:37 on dist-test-slave-k4wn"
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.254279 29391 fs_report.cc:383] FS layout report
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:04:37.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/wal
16:04:37.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/wal
16:04:37.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster7025442397405929848/master-0/data/data
16:04:37.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:04:37.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:04:37.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:04:37.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:04:37.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:04:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:04:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:04:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:04:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:04:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:04:37.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:04:37.269 [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
16:04:37.280 [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-taskjEqVoj/build/release/bin/kudu: process exited with non-zero status 1"
}

16:04:37.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T16:04:37Z chronyd exiting
16:04:37.319 [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-taskjEqVoj/build/release/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]
16:04:37.329 [INFO - main] (RandomUtils.java:49) Using random seed: 1783526677328
16:04:37.337 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
16:04:37.397 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
16:04:37.398 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskjEqVoj/test-tmp
16:04:37.398 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/release/bin
16:04:37.399 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskjEqVoj/java/build/dist-test/../../../build/release/bin/kudu, test, mini_cluster, --serialization=pb]
16:04:37.401 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128"
  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 {
  }
}

16:04:37.480 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
16:04:37.481 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

16:04:37.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.481925 29406 mini_oidc.cc:152] Starting JWKS server
16:04:37.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.483084 29406 webserver.cc:460] Webserver started at http://127.0.0.1:41859/ using document root <none> and password file <none>
16:04:37.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.483192 29406 mini_oidc.cc:189] Starting OIDC Discovery server
16:04:37.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:37.483417 29406 webserver.cc:460] Webserver started at http://127.0.0.1:35731/ using document root <none> and password file <none>
16:04:37.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
16:04:37.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/principal' for realm 'KRBTEST.COM',
16:04:37.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
16:04:37.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:37 dist-test-slave-k4wn krb5kdc[29416](info): setting up network...
16:04:37.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
16:04:37.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:37 dist-test-slave-k4wn krb5kdc[29416](info): set up 2 sockets
16:04:37.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:37 dist-test-slave-k4wn krb5kdc[29416](info): commencing operation
16:04:37.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
16:04:38.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.443570 29406 mini_kdc.cc:121] Time spent starting KDC: real 0.955s	user 0.002s	sys 0.004s
16:04:38.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
16:04:38.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:04:38.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
16:04:38.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
16:04:38.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:04:38.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
16:04:38.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
16:04:38.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:04:38.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
16:04:38.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
16:04:38.519 [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-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/test-user.keytab.
16:04:38.519 [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-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/test-user.keytab.
16:04:38.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:38 dist-test-slave-k4wn krb5kdc[29416](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526678, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:04:38.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
16:04:38.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T16:04:38Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
16:04:38.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T16:04:38Z Disabled control of system clock
16:04:38.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.183.190@KRBTEST.COM; defaulting to no policy
16:04:38.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:38.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.183.190@KRBTEST.COM" created.
16:04:38.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:38.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.190 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.190.keytab.
16:04:38.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.190 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.190.keytab.
16:04:38.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.568053 29406 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:38.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:38.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/wal
16:04:38.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data
16:04:38.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:04:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:04:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:04:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/logs
16:04:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data/info.pb
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.190:46611
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.190
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.190.keytab
16:04:38.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.190
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.183.190:46611
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41859/jwks.json
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:04:38.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:04:38.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.654460 29429 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:04:38.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.654695 29429 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:04:38.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.654721 29429 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:04:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.657747 29429 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
16:04:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.657892 29429 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:04:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.657929 29429 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:04:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.657946 29429 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
16:04:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.657966 29429 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
16:04:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.657985 29429 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
16:04:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.658000 29429 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:41859/jwks.json
16:04:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.660696 29429 master_runner.cc:386] Master server non-default flags:
16:04:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data
16:04:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/wal
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.183.190:46611
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.190.keytab
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.190
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.190:46611
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
16:04:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41859/jwks.json
16:04:38.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data/info.pb
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.190
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29429
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:38.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/logs
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:04:38.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:04:38.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
16:04:38.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 14:54:46 UTC on ccycloud.quasar-lejogm.root.comops.site
16:04:38.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493842
16:04:38.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.661026 29429 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:04:38.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.661413 29429 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:04:38.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.668612 29434 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
16:04:38.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.668716 29437 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
16:04:38.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.668716 29435 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
16:04:38.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.669190 29429 server_base.cc:1034] running on GCE node
16:04:38.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.669575 29429 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:04:38.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.670107 29429 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:04:38.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.671293 29429 hybrid_clock.cc:648] HybridClock initialized: now 1783526678671268 us; error 48 us; skew 500 ppm
16:04:38.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:38 dist-test-slave-k4wn krb5kdc[29416](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526678, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.183.190@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:04:38.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.679731 29429 init.cc:377] Logged in from keytab as kudu/127.28.183.190@KRBTEST.COM (short username kudu)
16:04:38.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.680519 29429 webserver.cc:460] Webserver started at http://127.28.183.190:46435/ using document root <none> and password file <none>
16:04:38.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.680924 29429 fs_manager.cc:359] Metadata directory not provided
16:04:38.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.681015 29429 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:04:38.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.681180 29429 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:04:38.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.682395 29429 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data/instance:
16:04:38.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d9674318a17448d98a51763911209988"
16:04:38.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:38 on dist-test-slave-k4wn"
16:04:38.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.682799 29429 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/wal/instance:
16:04:38.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d9674318a17448d98a51763911209988"
16:04:38.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:38 on dist-test-slave-k4wn"
16:04:38.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.684876 29429 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.002s	sys 0.002s
16:04:38.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.685971 29444 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:04:38.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.686144 29429 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
16:04:38.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.686226 29429 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data,/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/wal
16:04:38.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d9674318a17448d98a51763911209988"
16:04:38.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:38 on dist-test-slave-k4wn"
16:04:38.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.686324 29429 fs_report.cc:383] FS layout report
16:04:38.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:04:38.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/wal
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/wal
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data/data
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:04:38.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:04:38.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:04:38.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.698477 29429 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:04:38.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.701013 29429 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
16:04:38.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.701145 29429 kserver.cc:163] Server-wide thread pool size limit: 3276
16:04:38.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.705541 29429 rpc_server.cc:307] RPC server started. Bound to: 127.28.183.190:46611
16:04:38.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.705598 29496 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.28.183.190:46611 every 8 connection(s)
16:04:38.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.705919 29429 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/data/info.pb
16:04:38.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.706821 29497 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
16:04:38.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.709061 29497 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988: Bootstrap starting.
16:04:38.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.709762 29497 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988: Neither blocks nor log segments found. Creating new log.
16:04:38.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.710028 29497 log.cc:824] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988: Log is configured to *not* fsync() on all Append() calls
16:04:38.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.710721 29497 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988: No bootstrap required, opened a new log
16:04:38.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.713260 29497 raft_consensus.cc:348] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d9674318a17448d98a51763911209988" member_type: VOTER last_known_addr { host: "127.28.183.190" port: 46611 } }
16:04:38.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.713439 29497 raft_consensus.cc:374] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
16:04:38.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.713462 29497 raft_consensus.cc:729] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d9674318a17448d98a51763911209988, State: Initialized, Role: FOLLOWER
16:04:38.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.713598 29497 consensus_queue.cc:260] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [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: "d9674318a17448d98a51763911209988" member_type: VOTER last_known_addr { host: "127.28.183.190" port: 46611 } }
16:04:38.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.713683 29497 raft_consensus.cc:388] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
16:04:38.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.713708 29497 raft_consensus.cc:482] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
16:04:38.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.713737 29497 raft_consensus.cc:3037] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 0 FOLLOWER]: Advancing to term 1
16:04:38.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.714341 29497 raft_consensus.cc:504] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d9674318a17448d98a51763911209988" member_type: VOTER last_known_addr { host: "127.28.183.190" port: 46611 } }
16:04:38.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.714439 29497 leader_election.cc:302] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [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: d9674318a17448d98a51763911209988; no voters: 
16:04:38.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.714586 29497 leader_election.cc:288] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [CANDIDATE]: Term 1 election: Requested vote from peers 
16:04:38.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.714637 29500 raft_consensus.cc:2781] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 1 FOLLOWER]: Leader election won for term 1
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.714756 29500 raft_consensus.cc:686] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [term 1 LEADER]: Becoming Leader. State: Replica: d9674318a17448d98a51763911209988, State: Running, Role: LEADER
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.714836 29500 consensus_queue.cc:237] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [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: "d9674318a17448d98a51763911209988" member_type: VOTER last_known_addr { host: "127.28.183.190" port: 46611 } }
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.715070 29406 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjEqVoj/build/release/bin/kudu as pid 29429
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.715180 29406 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/master-0/wal/instance
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.715161 29500 sys_catalog.cc:455] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d9674318a17448d98a51763911209988" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d9674318a17448d98a51763911209988" member_type: VOTER last_known_addr { host: "127.28.183.190" port: 46611 } } }
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.715224 29500 sys_catalog.cc:458] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [sys.catalog]: This master's current role is: LEADER
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.715329 29500 sys_catalog.cc:455] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [sys.catalog]: SysCatalogTable state changed. Reason: New leader d9674318a17448d98a51763911209988. Latest consensus state: current_term: 1 leader_uuid: "d9674318a17448d98a51763911209988" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d9674318a17448d98a51763911209988" member_type: VOTER last_known_addr { host: "127.28.183.190" port: 46611 } } }
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.715368 29500 sys_catalog.cc:458] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [sys.catalog]: This master's current role is: LEADER
16:04:38.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.715592 29497 sys_catalog.cc:564] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988 [sys.catalog]: configured and running, proceeding with master startup.
16:04:38.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.717401 29503 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
16:04:38.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.718541 29503 catalog_manager.cc:1484] Initializing Kudu cluster ID...
16:04:38.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.722462 29503 catalog_manager.cc:1347] Generated new cluster ID: 48f423e3743146dd94d3cc824482c62b
16:04:38.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.722519 29503 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
16:04:38.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:38 dist-test-slave-k4wn krb5kdc[29416](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526678, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.28.183.190@KRBTEST.COM
16:04:38.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.731276 29517 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:04:38.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.717266 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:52858 (local address 127.28.183.190:46611)
16:04:38.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.717420 (+   154us) server_negotiation.cc:206] Beginning negotiation
16:04:38.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.717430 (+    10us) server_negotiation.cc:399] Waiting for connection header
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.717594 (+   164us) server_negotiation.cc:407] Connection header received
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.718808 (+  1214us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.718816 (+     8us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.718874 (+    58us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.718980 (+   106us) server_negotiation.cc:226] Negotiated authn=SASL
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.721204 (+  2224us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.722775 (+  1571us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.724278 (+  1503us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.724684 (+   406us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.724788 (+   104us) 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
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.727924 (+  3136us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.727929 (+     5us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.727930 (+     1us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.727943 (+    13us) server_negotiation.cc:922] calling sasl_server_start()
16:04:38.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.729435 (+  1492us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:38.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.729859 (+   424us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:38.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.729862 (+     3us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:38.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.729862 (+     0us) server_negotiation.cc:967] calling sasl_server_step()
16:04:38.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.729948 (+    86us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.730204 (+   256us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.730205 (+     1us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.730206 (+     1us) server_negotiation.cc:967] calling sasl_server_step()
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.730455 (+   249us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.730606 (+   151us) server_negotiation.cc:1035] Waiting for connection context
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.730864 (+   258us) server_negotiation.cc:299] Negotiation successful
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.731054 (+   190us) negotiation.cc:327] Negotiation complete: OK
16:04:38.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":108,"thread_start_us":60,"threads_started":1}
16:04:38.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.737862 29503 catalog_manager.cc:1370] Generated new certificate authority record
16:04:38.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.739001 29503 catalog_manager.cc:1504] Loading token signing keys...
16:04:38.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.748744 29503 catalog_manager.cc:5868] T 00000000000000000000000000000000 P d9674318a17448d98a51763911209988: Generated new TSK 0
16:04:38.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.748936 29503 catalog_manager.cc:1514] Initializing in-progress tserver states...
16:04:38.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.183.129@KRBTEST.COM; defaulting to no policy
16:04:38.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:38.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.183.129@KRBTEST.COM" created.
16:04:38.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:38.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.129 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.129.keytab.
16:04:38.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.129 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.129.keytab.
16:04:38.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.780676 29406 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:38.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/wal
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/logs
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data/info.pb
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
16:04:38.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.129:0
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.183.129
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.129
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.183.190:46611
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.129.keytab
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.129
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:04:38.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:04:38.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.867740 29523 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:04:38.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.868016 29523 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:04:38.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.868052 29523 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:04:38.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.869782 29523 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:04:38.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.869845 29523 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:04:38.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.869896 29523 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.183.129
16:04:38.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.871712 29523 tablet_server_runner.cc:78] Tablet server non-default flags:
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/wal
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.129.keytab
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.129
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.129:0
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data/info.pb
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.129
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.183.190:46611
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29523
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.183.129
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/logs
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:04:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
16:04:38.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:04:38.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:04:38.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
16:04:38.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 14:54:46 UTC on ccycloud.quasar-lejogm.root.comops.site
16:04:38.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493842
16:04:38.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.872014 29523 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:04:38.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.872291 29523 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:04:38.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.879040 29531 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
16:04:38.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.879040 29528 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
16:04:38.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:38.879261 29529 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
16:04:38.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.879835 29523 server_base.cc:1034] running on GCE node
16:04:38.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.880077 29523 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:04:38.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.880355 29523 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:04:38.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.881520 29523 hybrid_clock.cc:648] HybridClock initialized: now 1783526678881509 us; error 35 us; skew 500 ppm
16:04:38.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:38 dist-test-slave-k4wn krb5kdc[29416](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526678, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.183.129@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:04:38.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.884244 29523 init.cc:377] Logged in from keytab as kudu/127.28.183.129@KRBTEST.COM (short username kudu)
16:04:38.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.884804 29523 webserver.cc:460] Webserver started at http://127.28.183.129:35819/ using document root <none> and password file <none>
16:04:38.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.885010 29523 fs_manager.cc:359] Metadata directory not provided
16:04:38.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.885046 29523 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:04:38.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.885152 29523 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:04:38.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.886148 29523 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data/instance:
16:04:38.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3d5476dfdf1346b7b873158448d06e1a"
16:04:38.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:38 on dist-test-slave-k4wn"
16:04:38.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.886456 29523 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/wal/instance:
16:04:38.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3d5476dfdf1346b7b873158448d06e1a"
16:04:38.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:38 on dist-test-slave-k4wn"
16:04:38.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.887737 29523 fs_manager.cc:691] Time spent creating directory manager: real 0.001s	user 0.002s	sys 0.000s
16:04:38.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.888480 29538 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:04:38.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.888631 29523 fs_manager.cc:725] Time spent opening block manager: real 0.000s	user 0.000s	sys 0.000s
16:04:38.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.888701 29523 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data,/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/wal
16:04:38.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3d5476dfdf1346b7b873158448d06e1a"
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:38 on dist-test-slave-k4wn"
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.888754 29523 fs_report.cc:383] FS layout report
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/wal
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/wal
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data/data
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:04:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:04:38.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:04:38.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:04:38.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:04:38.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:04:38.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:04:38.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.902318 29523 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:04:38.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.905462 29523 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
16:04:38.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.905607 29523 kserver.cc:163] Server-wide thread pool size limit: 3276
16:04:38.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.905827 29523 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
16:04:38.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.906213 29523 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
16:04:38.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.906262 29523 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
16:04:38.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.906286 29523 ts_tablet_manager.cc:610] Registered 0 tablets
16:04:38.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.906296 29523 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
16:04:38.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.912405 29523 rpc_server.cc:307] RPC server started. Bound to: 127.28.183.129:38905
16:04:38.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.912487 29651 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.28.183.129:38905 every 8 connection(s)
16:04:38.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.912811 29523 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/data/info.pb
16:04:38.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.916253 29406 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjEqVoj/build/release/bin/kudu as pid 29523
16:04:38.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.916363 29406 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-0/wal/instance
16:04:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:38 dist-test-slave-k4wn krb5kdc[29416](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526678, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.183.129@KRBTEST.COM for kudu/127.28.183.190@KRBTEST.COM
16:04:38.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.924327 29517 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:04:38.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.913532 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.28.183.129:41347 (local address 127.28.183.190:46611)
16:04:38.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.913579 (+    47us) server_negotiation.cc:206] Beginning negotiation
16:04:38.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.913581 (+     2us) server_negotiation.cc:399] Waiting for connection header
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.913784 (+   203us) server_negotiation.cc:407] Connection header received
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.914800 (+  1016us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.914801 (+     1us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.914812 (+    11us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.914887 (+    75us) server_negotiation.cc:226] Negotiated authn=SASL
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.915663 (+   776us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.916384 (+   721us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.917685 (+  1301us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.917974 (+   289us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.918049 (+    75us) 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
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.920905 (+  2856us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.920908 (+     3us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.920908 (+     0us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:04:38.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.920922 (+    14us) server_negotiation.cc:922] calling sasl_server_start()
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.922373 (+  1451us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923046 (+   673us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923047 (+     1us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923048 (+     1us) server_negotiation.cc:967] calling sasl_server_step()
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923157 (+   109us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923561 (+   404us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923563 (+     2us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923563 (+     0us) server_negotiation.cc:967] calling sasl_server_step()
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923699 (+   136us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923766 (+    67us) server_negotiation.cc:1035] Waiting for connection context
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.924194 (+   428us) server_negotiation.cc:299] Negotiation successful
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.924252 (+    58us) negotiation.cc:327] Negotiation complete: OK
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":18}
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.924503 29654 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.913505 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.28.183.190:46611 (local address 127.28.183.129:41347)
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.913664 (+   159us) negotiation.cc:107] Waiting for socket to connect
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.913708 (+    44us) client_negotiation.cc:174] Beginning negotiation
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.914677 (+   969us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.915048 (+   371us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.915051 (+     3us) client_negotiation.cc:394] Received NEGOTIATE response from server
16:04:38.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.915321 (+   270us) client_negotiation.cc:189] Negotiated authn=SASL
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.915561 (+   240us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.915566 (+     5us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.916511 (+   945us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.916513 (+     2us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.917593 (+  1080us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.917597 (+     4us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.918075 (+   478us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.918083 (+     8us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.918250 (+   167us) 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
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.918876 (+   626us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.918888 (+    12us) client_negotiation.cc:656] calling sasl_client_start()
16:04:38.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.920686 (+  1798us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.922508 (+  1822us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.922521 (+    13us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.922525 (+     4us) client_negotiation.cc:760] calling sasl_client_step()
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.922917 (+   392us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923280 (+   363us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923281 (+     1us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923281 (+     0us) client_negotiation.cc:760] calling sasl_client_step()
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923431 (+   150us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923790 (+   359us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.923797 (+     7us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.924016 (+   219us) client_negotiation.cc:769] Sending connection context
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.924158 (+   142us) client_negotiation.cc:240] Negotiation successful
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:38.924263 (+   105us) negotiation.cc:327] Negotiation complete: OK
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":119,"thread_start_us":72,"threads_started":1}
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.183.130@KRBTEST.COM; defaulting to no policy
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.925087 29652 heartbeater.cc:347] Connected to a master server at 127.28.183.190:46611
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.925168 29652 heartbeater.cc:464] Registering TS with master...
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.925315 29652 heartbeater.cc:511] Master 127.28.183.190:46611 requested a full tablet report, sending...
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.925864 29461 ts_manager.cc:194] Registered new tserver with Master: 3d5476dfdf1346b7b873158448d06e1a (127.28.183.129:38905)
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.926725 29461 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.28.183.129@KRBTEST.COM'} at 127.28.183.129:41347
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:38.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.183.130@KRBTEST.COM" created.
16:04:38.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.130 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.130.keytab.
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.130 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.130.keytab.
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:38.943280 29406 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/wal
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/logs
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data/info.pb
16:04:38.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.130:0
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.183.130
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.130
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.183.190:46611
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.130.keytab
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.130
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:38.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:04:38.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:04:39.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.030226 29657 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:04:39.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.030444 29657 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:04:39.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.030470 29657 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:04:39.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.031670 29657 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:04:39.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.031713 29657 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:04:39.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.031741 29657 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.183.130
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.033301 29657 tablet_server_runner.cc:78] Tablet server non-default flags:
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/wal
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.130.keytab
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.130
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.130:0
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data/info.pb
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.130
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.183.190:46611
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29657
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.183.130
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/logs
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 14:54:46 UTC on ccycloud.quasar-lejogm.root.comops.site
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493842
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.033499 29657 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:04:39.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.033685 29657 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:04:39.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.038789 29663 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
16:04:39.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.038825 29662 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
16:04:39.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.038789 29665 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
16:04:39.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.039916 29657 server_base.cc:1034] running on GCE node
16:04:39.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.040181 29657 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:04:39.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.040402 29657 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:04:39.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.041563 29657 hybrid_clock.cc:648] HybridClock initialized: now 1783526679041534 us; error 37 us; skew 500 ppm
16:04:39.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:39 dist-test-slave-k4wn krb5kdc[29416](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526679, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.183.130@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:04:39.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.045115 29657 init.cc:377] Logged in from keytab as kudu/127.28.183.130@KRBTEST.COM (short username kudu)
16:04:39.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.045562 29657 webserver.cc:460] Webserver started at http://127.28.183.130:42111/ using document root <none> and password file <none>
16:04:39.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.045753 29657 fs_manager.cc:359] Metadata directory not provided
16:04:39.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.045792 29657 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:04:39.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.045902 29657 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:04:39.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.046845 29657 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data/instance:
16:04:39.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "75454f7a30fc493e8a423f3f68c1a215"
16:04:39.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:39 on dist-test-slave-k4wn"
16:04:39.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.047137 29657 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/wal/instance:
16:04:39.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "75454f7a30fc493e8a423f3f68c1a215"
16:04:39.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:39 on dist-test-slave-k4wn"
16:04:39.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.048913 29657 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.003s	sys 0.000s
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.049914 29672 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.050133 29657 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.050215 29657 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data,/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/wal
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "75454f7a30fc493e8a423f3f68c1a215"
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:39 on dist-test-slave-k4wn"
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.050295 29657 fs_report.cc:383] FS layout report
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/wal
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/wal
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data/data
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:04:39.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:04:39.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:04:39.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:04:39.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:04:39.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:04:39.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.073752 29657 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:04:39.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.076126 29657 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
16:04:39.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.076257 29657 kserver.cc:163] Server-wide thread pool size limit: 3276
16:04:39.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.076467 29657 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
16:04:39.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.076756 29657 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
16:04:39.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.076803 29657 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
16:04:39.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.076828 29657 ts_tablet_manager.cc:610] Registered 0 tablets
16:04:39.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.076839 29657 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
16:04:39.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.083406 29657 rpc_server.cc:307] RPC server started. Bound to: 127.28.183.130:34739
16:04:39.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.083475 29785 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.28.183.130:34739 every 8 connection(s)
16:04:39.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.084193 29657 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/data/info.pb
16:04:39.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.091027 29406 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjEqVoj/build/release/bin/kudu as pid 29657
16:04:39.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.091178 29406 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-1/wal/instance
16:04:39.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:39 dist-test-slave-k4wn krb5kdc[29416](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526679, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.183.130@KRBTEST.COM for kudu/127.28.183.190@KRBTEST.COM
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.097662 29517 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.085061 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.28.183.130:34489 (local address 127.28.183.190:46611)
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.085133 (+    72us) server_negotiation.cc:206] Beginning negotiation
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.085136 (+     3us) server_negotiation.cc:399] Waiting for connection header
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.085473 (+   337us) server_negotiation.cc:407] Connection header received
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.086833 (+  1360us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.086835 (+     2us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.086846 (+    11us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.086910 (+    64us) server_negotiation.cc:226] Negotiated authn=SASL
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.087931 (+  1021us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.088466 (+   535us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.090140 (+  1674us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:39.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.090468 (+   328us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.090571 (+   103us) 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
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.093957 (+  3386us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.093958 (+     1us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.093959 (+     1us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.093969 (+    10us) server_negotiation.cc:922] calling sasl_server_start()
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.095525 (+  1556us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096256 (+   731us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096257 (+     1us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096258 (+     1us) server_negotiation.cc:967] calling sasl_server_step()
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096338 (+    80us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096751 (+   413us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096752 (+     1us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096752 (+     0us) server_negotiation.cc:967] calling sasl_server_step()
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096927 (+   175us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097020 (+    93us) server_negotiation.cc:1035] Waiting for connection context
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097479 (+   459us) server_negotiation.cc:299] Negotiation successful
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097555 (+    76us) negotiation.cc:327] Negotiation complete: OK
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":39}
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.097858 29788 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.085041 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.28.183.190:46611 (local address 127.28.183.130:34489)
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.085302 (+   261us) negotiation.cc:107] Waiting for socket to connect
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.085376 (+    74us) client_negotiation.cc:174] Beginning negotiation
16:04:39.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.086665 (+  1289us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
16:04:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.087032 (+   367us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
16:04:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.087037 (+     5us) client_negotiation.cc:394] Received NEGOTIATE response from server
16:04:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.087440 (+   403us) client_negotiation.cc:189] Negotiated authn=SASL
16:04:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.087739 (+   299us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:04:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.087747 (+     8us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:04:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.088573 (+   826us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:04:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.088574 (+     1us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:04:39.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.089951 (+  1377us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:04:39.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.089956 (+     5us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.090599 (+   643us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.090600 (+     1us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.090766 (+   166us) 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
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.091726 (+   960us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.091736 (+    10us) client_negotiation.cc:656] calling sasl_client_start()
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.093788 (+  2052us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.095702 (+  1914us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.095717 (+    15us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.095720 (+     3us) client_negotiation.cc:760] calling sasl_client_step()
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096143 (+   423us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096486 (+   343us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096487 (+     1us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:04:39.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096487 (+     0us) client_negotiation.cc:760] calling sasl_client_step()
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.096658 (+   171us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097062 (+   404us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097069 (+     7us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097287 (+   218us) client_negotiation.cc:769] Sending connection context
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097419 (+   132us) client_negotiation.cc:240] Negotiation successful
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.097524 (+   105us) negotiation.cc:327] Negotiation complete: OK
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":206,"thread_start_us":109,"threads_started":1}
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.183.131@KRBTEST.COM; defaulting to no policy
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.101687 29786 heartbeater.cc:347] Connected to a master server at 127.28.183.190:46611
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.101831 29786 heartbeater.cc:464] Registering TS with master...
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.102062 29786 heartbeater.cc:511] Master 127.28.183.190:46611 requested a full tablet report, sending...
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.102795 29461 ts_manager.cc:194] Registered new tserver with Master: 75454f7a30fc493e8a423f3f68c1a215 (127.28.183.130:34739)
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.183.131@KRBTEST.COM" created.
16:04:39.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.103367 29461 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.28.183.130@KRBTEST.COM'} at 127.28.183.130:34489
16:04:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
16:04:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.131 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.131.keytab.
16:04:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.183.131 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.131.keytab.
16:04:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.119702 29406 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:39.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskjEqVoj/build/release/bin/kudu
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/wal
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/logs
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data/info.pb
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:39.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.131:0
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.183.131
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.131
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.183.190:46611
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.131.keytab
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.131
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
16:04:39.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
16:04:39.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.219511 29791 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
16:04:39.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.219740 29791 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
16:04:39.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.219769 29791 flags.cc:425] Enabled unsafe flag: --never_fsync=true
16:04:39.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.221167 29791 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
16:04:39.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.221225 29791 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
16:04:39.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.221257 29791 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.183.131
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.223111 29791 tablet_server_runner.cc:78] Tablet server non-default flags:
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.183.148:34823
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/wal
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kudu_127.28.183.131.keytab
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.183.131
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.183.131:0
16:04:39.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data/info.pb
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.183.131
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.183.190:46611
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29791
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.183.131
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/logs
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type RELEASE
16:04:39.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 14:54:46 UTC on ccycloud.quasar-lejogm.root.comops.site
16:04:39.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4493842
16:04:39.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.223356 29791 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
16:04:39.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.223565 29791 file_cache.cc:493] Constructed file cache file cache with capacity 419430
16:04:39.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.229040 29797 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
16:04:39.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.229084 29799 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
16:04:39.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.229171 29796 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
16:04:39.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.229743 29791 server_base.cc:1034] running on GCE node
16:04:39.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.230046 29791 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
16:04:39.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.230358 29791 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
16:04:39.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.231540 29791 hybrid_clock.cc:648] HybridClock initialized: now 1783526679231519 us; error 37 us; skew 500 ppm
16:04:39.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:39 dist-test-slave-k4wn krb5kdc[29416](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526679, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.183.131@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
16:04:39.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.236167 29791 init.cc:377] Logged in from keytab as kudu/127.28.183.131@KRBTEST.COM (short username kudu)
16:04:39.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.237164 29791 webserver.cc:460] Webserver started at http://127.28.183.131:45573/ using document root <none> and password file <none>
16:04:39.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.237432 29791 fs_manager.cc:359] Metadata directory not provided
16:04:39.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.237495 29791 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
16:04:39.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.237628 29791 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
16:04:39.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.238960 29791 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data/instance:
16:04:39.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d2e7ce26d314ab9adf9b9a9eb1d524d"
16:04:39.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:39 on dist-test-slave-k4wn"
16:04:39.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.239400 29791 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/wal/instance:
16:04:39.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d2e7ce26d314ab9adf9b9a9eb1d524d"
16:04:39.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:39 on dist-test-slave-k4wn"
16:04:39.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.241156 29791 fs_manager.cc:691] Time spent creating directory manager: real 0.002s	user 0.004s	sys 0.000s
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.242251 29806 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.242450 29791 fs_manager.cc:725] Time spent opening block manager: real 0.001s	user 0.001s	sys 0.000s
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.242542 29791 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data,/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/wal
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d2e7ce26d314ab9adf9b9a9eb1d524d"
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 16:04:39 on dist-test-slave-k4wn"
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.242611 29791 fs_report.cc:383] FS layout report
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/wal
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/wal
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data/data
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
16:04:39.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
16:04:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
16:04:39.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
16:04:39.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.253232 29791 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
16:04:39.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.255343 29791 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
16:04:39.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.255460 29791 kserver.cc:163] Server-wide thread pool size limit: 3276
16:04:39.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.255666 29791 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
16:04:39.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.255962 29791 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
16:04:39.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.255990 29791 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
16:04:39.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.256011 29791 ts_tablet_manager.cc:610] Registered 0 tablets
16:04:39.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.256021 29791 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
16:04:39.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.261803 29791 rpc_server.cc:307] RPC server started. Bound to: 127.28.183.131:35249
16:04:39.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.261848 29919 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.28.183.131:35249 every 8 connection(s)
16:04:39.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.262138 29791 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/data/info.pb
16:04:39.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.266480 29406 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskjEqVoj/build/release/bin/kudu as pid 29791
16:04:39.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.266587 29406 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/ts-2/wal/instance
16:04:39.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 16:04:39 dist-test-slave-k4wn krb5kdc[29416](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783526679, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.183.131@KRBTEST.COM for kudu/127.28.183.190@KRBTEST.COM
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.274030 29517 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.262802 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.28.183.131:54271 (local address 127.28.183.190:46611)
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.262847 (+    45us) server_negotiation.cc:206] Beginning negotiation
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.262849 (+     2us) server_negotiation.cc:399] Waiting for connection header
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.263067 (+   218us) server_negotiation.cc:407] Connection header received
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264294 (+  1227us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264296 (+     2us) server_negotiation.cc:461] Received NEGOTIATE request from client
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264314 (+    18us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264372 (+    58us) server_negotiation.cc:226] Negotiated authn=SASL
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.265202 (+   830us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.265795 (+   593us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267234 (+  1439us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
16:04:39.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267520 (+   286us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267590 (+    70us) 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
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.270549 (+  2959us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.270551 (+     2us) server_negotiation.cc:881] Received SASL_INITIATE request from client
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.270551 (+     0us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.270562 (+    11us) server_negotiation.cc:922] calling sasl_server_start()
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272027 (+  1465us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272744 (+   717us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272746 (+     2us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272746 (+     0us) server_negotiation.cc:967] calling sasl_server_step()
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272822 (+    76us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273163 (+   341us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273163 (+     0us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273164 (+     1us) server_negotiation.cc:967] calling sasl_server_step()
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273361 (+   197us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273448 (+    87us) server_negotiation.cc:1035] Waiting for connection context
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273822 (+   374us) server_negotiation.cc:299] Negotiation successful
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273913 (+    91us) negotiation.cc:327] Negotiation complete: OK
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":17}
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.274274 29922 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.262772 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.28.183.190:46611 (local address 127.28.183.131:54271)
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.262927 (+   155us) negotiation.cc:107] Waiting for socket to connect
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.262975 (+    48us) client_negotiation.cc:174] Beginning negotiation
16:04:39.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264141 (+  1166us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264475 (+   334us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264481 (+     6us) client_negotiation.cc:394] Received NEGOTIATE response from server
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.264847 (+   366us) client_negotiation.cc:189] Negotiated authn=SASL
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.265092 (+   245us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.265097 (+     5us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.265915 (+   818us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.265917 (+     2us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267088 (+  1171us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267093 (+     5us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267608 (+   515us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267610 (+     2us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.267808 (+   198us) 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
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.268529 (+   721us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.268541 (+    12us) client_negotiation.cc:656] calling sasl_client_start()
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.270357 (+  1816us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272180 (+  1823us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:04:39.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272201 (+    21us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272204 (+     3us) client_negotiation.cc:760] calling sasl_client_step()
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272636 (+   432us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272904 (+   268us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272905 (+     1us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.272906 (+     1us) client_negotiation.cc:760] calling sasl_client_step()
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273067 (+   161us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273457 (+   390us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273466 (+     9us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273656 (+   190us) client_negotiation.cc:769] Sending connection context
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273776 (+   120us) client_negotiation.cc:240] Negotiation successful
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0708 16:04:39.273969 (+   193us) negotiation.cc:327] Negotiation complete: OK
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":111,"thread_start_us":70,"threads_started":1}
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.275056 29920 heartbeater.cc:347] Connected to a master server at 127.28.183.190:46611
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.275163 29920 heartbeater.cc:464] Registering TS with master...
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.275373 29920 heartbeater.cc:511] Master 127.28.183.190:46611 requested a full tablet report, sending...
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.275866 29461 ts_manager.cc:194] Registered new tserver with Master: 0d2e7ce26d314ab9adf9b9a9eb1d524d (127.28.183.131:35249)
16:04:39.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.276454 29461 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.28.183.131@KRBTEST.COM'} at 127.28.183.131:54271
16:04:39.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.279433 29406 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
16:04:39.281 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
16:04:39.282 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

16:04:39.293 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

16:04:39.294 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

16:04:39.304 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.28.183.190"
      port: 46611
    }
    bound_http_address {
      host: "127.28.183.190"
      port: 46435
    }
  }
}

16:04:39.308 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

16:04:39.310 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.28.183.129"
      port: 38905
    }
    bound_http_address {
      host: "127.28.183.129"
      port: 35819
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.28.183.130"
      port: 34739
    }
    bound_http_address {
      host: "127.28.183.130"
      port: 42111
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.28.183.131"
      port: 35249
    }
    bound_http_address {
      host: "127.28.183.131"
      port: 45573
    }
  }
}

16:04:39.311 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
16:04:39.529 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskjEqVoj/test-tmp/mini-kudu-cluster4276163036988282128/krb5kdc/krb5cc
16:04:39.539 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
16:04:39.556 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
16:04:39.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.560141 29406 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjEqVoj/build/release/bin/kudu with pid 29523
16:04:39.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.564558 29448 connection.cc:535] server connection from 127.28.183.129:41347 recv error: Network error: failed to read from TLS socket (remote: 127.28.183.129:41347): Connection reset by peer (error 104)
16:04:39.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.564697 29406 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjEqVoj/build/release/bin/kudu with pid 29657
16:04:39.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.569121 29448 connection.cc:535] server connection from 127.28.183.130:34489 recv error: Network error: failed to read from TLS socket (remote: 127.28.183.130:34489): Connection reset by peer (error 104)
16:04:39.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.569324 29406 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjEqVoj/build/release/bin/kudu with pid 29791
16:04:39.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.573258 29448 connection.cc:535] server connection from 127.28.183.131:54271 recv error: Network error: failed to read from TLS socket (remote: 127.28.183.131:54271): Connection reset by peer (error 104)
16:04:39.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 16:04:39.573378 29406 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskjEqVoj/build/release/bin/kudu with pid 29429
16:04:39.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 16:04:39.577550 29414 connection.cc:535] client connection to 127.28.183.190:46611 recv error: Network error: failed to read from TLS socket (remote: 127.28.183.190:46611): Connection reset by peer (error 104)
16:04:39.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T16:04:39Z chronyd exiting

Time: 3.245

OK (1 test)

