JUnit version 4.13.2
.22:22:01.549 [DEBUG - main] (TestNegotiationTLSv13.java:108) enabled TLS protocols: TLSv1.3 TLSv1.2 TLSv1.1 TLSv1
22:22:01.552 [DEBUG - main] (TestNegotiationTLSv13.java:110) supported TLS protocols: TLSv1.3 TLSv1.2 SSLv3 TLSv1.1 SSLv2Hello TLSv1
22:22:01.561 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskH0iKQE/test-tmp
22:22:01.563 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/debug/bin
22:22:01.565 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskH0iKQE/java/build/dist-test/../../../build/debug/bin/kudu, test, mini_cluster, --serialization=pb]
22:22:01.791 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 0
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666"
  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 {
  }
}

22:22:01.804 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
22:22:01.805 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

22:22:01.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.806704   793 mini_oidc.cc:152] Starting JWKS server
22:22:01.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.809561   793 webserver.cc:460] Webserver started at http://127.0.0.1:43677/ using document root <none> and password file <none>
22:22:01.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.809789   793 mini_oidc.cc:189] Starting OIDC Discovery server
22:22:01.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.810112   793 webserver.cc:460] Webserver started at http://127.0.0.1:35847/ using document root <none> and password file <none>
22:22:01.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T22:22:01Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
22:22:01.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T22:22:01Z Disabled control of system clock
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.830854   793 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/wal
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/data
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:01.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/logs
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/data/info.pb
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
22:22:01.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.198.126:43223
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.198.126
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.198.84:39819
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
22:22:01.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.0.198.126:43223
22:22:01.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
22:22:01.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:43677/jwks.json
22:22:01.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
22:22:01.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3 with env {}
22:22:01.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.938823   805 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
22:22:01.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.939062   805 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
22:22:01.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.939105   805 flags.cc:425] Enabled unsafe flag: --never_fsync=true
22:22:01.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.943122   805 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
22:22:01.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.943178   805 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
22:22:01.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.943195   805 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
22:22:01.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.943209   805 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
22:22:01.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.943223   805 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
22:22:01.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.943235   805 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:43677/jwks.json
22:22:01.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.950083   805 master_runner.cc:386] Master server non-default flags:
22:22:01.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:01.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.198.84:39819
22:22:01.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=system_unsync
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/data
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/wal
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.0.198.126:43223
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.198.126:43223
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
22:22:01.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:43677/jwks.json
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_tls_min_protocol=TLSv1.3
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/data/info.pb
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.198.126
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.805
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:01.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/logs
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 21:46:50 UTC on ccycloud.quasar-irklax.root.comops.site
22:22:01.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492326
22:22:01.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.951560   805 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
22:22:01.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.953405   805 file_cache.cc:493] Constructed file cache file cache with capacity 419430
22:22:01.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.963918   810 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
22:22:01.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.963912   811 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
22:22:01.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.963919   805 server_base.cc:1034] running on GCE node
22:22:01.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.963937   813 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
22:22:01.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.965005   805 hybrid_clock.cc:584] initializing the hybrid clock with 'system_unsync' time source
22:22:01.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:01.965101   805 system_unsync_time.cc:38] NTP support is disabled. Clock error bounds will not be accurate. This configuration is not suitable for distributed clusters.
22:22:01.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.965142   805 hybrid_clock.cc:648] HybridClock initialized: now 1783462921965138 us; error 0 us; skew 500 ppm
22:22:01.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.967422   805 webserver.cc:460] Webserver started at http://127.0.198.126:36175/ using document root <none> and password file <none>
22:22:01.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.967958   805 fs_manager.cc:359] Metadata directory not provided
22:22:01.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.968014   805 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
22:22:01.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.968185   805 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
22:22:01.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.970039   805 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/data/instance:
22:22:01.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b6aefe7ced5443dea044525b842dd715"
22:22:01.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:01 on dist-test-slave-5pk4"
22:22:01.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.970609   805 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/wal/instance:
22:22:01.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b6aefe7ced5443dea044525b842dd715"
22:22:01.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:01 on dist-test-slave-5pk4"
22:22:01.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.974350   805 fs_manager.cc:691] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.000s
22:22:01.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.976774   818 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.978116   805 fs_manager.cc:725] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.978240   805 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/data,/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/wal
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b6aefe7ced5443dea044525b842dd715"
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:01 on dist-test-slave-5pk4"
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:01.978318   805 fs_report.cc:383] FS layout report
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/wal
22:22:01.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/wal
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster4968726766441938666/master-0/data/data
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
22:22:01.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
22:22:01.996 [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
22:22:02.009 [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-taskH0iKQE/build/debug/bin/kudu: process exited with non-zero status 1"
}

22:22:02.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T22:22:02Z chronyd exiting
22:22:02.051 [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-taskH0iKQE/build/debug/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]
22:22:02.061 [INFO - main] (RandomUtils.java:49) Using random seed: 1783462922061
22:22:02.068 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
22:22:02.127 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
22:22:02.127 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskH0iKQE/test-tmp
22:22:02.128 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/debug/bin
22:22:02.128 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskH0iKQE/java/build/dist-test/../../../build/debug/bin/kudu, test, mini_cluster, --serialization=pb]
22:22:02.130 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519"
  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 {
  }
}

22:22:02.247 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
22:22:02.248 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

22:22:02.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:02.248991   820 mini_oidc.cc:152] Starting JWKS server
22:22:02.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:02.251083   820 webserver.cc:460] Webserver started at http://127.0.0.1:33023/ using document root <none> and password file <none>
22:22:02.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:02.251240   820 mini_oidc.cc:189] Starting OIDC Discovery server
22:22:02.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:02.251551   820 webserver.cc:460] Webserver started at http://127.0.0.1:38911/ using document root <none> and password file <none>
22:22:02.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
22:22:02.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/principal' for realm 'KRBTEST.COM',
22:22:02.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
22:22:02.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:02 dist-test-slave-5pk4 krb5kdc[830](info): setting up network...
22:22:02.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
22:22:02.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:02 dist-test-slave-5pk4 krb5kdc[830](info): set up 2 sockets
22:22:02.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:02 dist-test-slave-5pk4 krb5kdc[830](info): commencing operation
22:22:02.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
22:22:03.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.210836   820 mini_kdc.cc:121] Time spent starting KDC: real 0.952s	user 0.006s	sys 0.000s
22:22:03.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
22:22:03.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
22:22:03.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
22:22:03.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
22:22:03.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
22:22:03.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
22:22:03.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
22:22:03.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
22:22:03.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
22:22:03.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
22:22:03.285 [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-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/test-user.keytab.
22:22:03.285 [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-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/test-user.keytab.
22:22:03.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:03 dist-test-slave-5pk4 krb5kdc[830](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462923, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
22:22:03.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
22:22:03.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T22:22:03Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
22:22:03.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T22:22:03Z Disabled control of system clock
22:22:03.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.0.205.62@KRBTEST.COM; defaulting to no policy
22:22:03.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:03.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.0.205.62@KRBTEST.COM" created.
22:22:03.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:03.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.62.keytab.
22:22:03.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.62.keytab.
22:22:03.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.327603   820 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/wal
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:03.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/logs
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data/info.pb
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:03.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.62:38421
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.62
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.62.keytab
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.62
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
22:22:03.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.0.205.62:38421
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33023/jwks.json
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
22:22:03.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
22:22:03.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.433382   843 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
22:22:03.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.433619   843 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
22:22:03.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.433671   843 flags.cc:425] Enabled unsafe flag: --never_fsync=true
22:22:03.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.439890   843 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
22:22:03.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.439963   843 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
22:22:03.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.439988   843 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
22:22:03.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.440009   843 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
22:22:03.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.440028   843 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
22:22:03.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.440048   843 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
22:22:03.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.440068   843 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:33023/jwks.json
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.447942   843 master_runner.cc:386] Master server non-default flags:
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/wal
22:22:03.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.0.205.62:38421
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.62.keytab
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.62
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.62:38421
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
22:22:03.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
22:22:03.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:33023/jwks.json
22:22:03.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data/info.pb
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.62
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.843
22:22:03.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/logs
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 21:46:50 UTC on ccycloud.quasar-irklax.root.comops.site
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492326
22:22:03.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.449343   843 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
22:22:03.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.450680   843 file_cache.cc:493] Constructed file cache file cache with capacity 419430
22:22:03.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.459239   848 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
22:22:03.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.459244   851 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
22:22:03.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.459229   849 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
22:22:03.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.459529   843 server_base.cc:1034] running on GCE node
22:22:03.460 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.460109   843 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
22:22:03.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.461179   843 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
22:22:03.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.462332   843 hybrid_clock.cc:648] HybridClock initialized: now 1783462923462277 us; error 65 us; skew 500 ppm
22:22:03.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:03 dist-test-slave-5pk4 krb5kdc[830](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462923, etypes {rep=17 tkt=17 ses=17}, kudu/127.0.205.62@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
22:22:03.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.464953   843 init.cc:377] Logged in from keytab as kudu/127.0.205.62@KRBTEST.COM (short username kudu)
22:22:03.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.465969   843 webserver.cc:460] Webserver started at http://127.0.205.62:33423/ using document root <none> and password file <none>
22:22:03.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.466583   843 fs_manager.cc:359] Metadata directory not provided
22:22:03.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.466650   843 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
22:22:03.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.466802   843 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
22:22:03.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.468647   843 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data/instance:
22:22:03.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "152d9c4491be4c38845c27dba09653b4"
22:22:03.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.469130   843 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/wal/instance:
22:22:03.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "152d9c4491be4c38845c27dba09653b4"
22:22:03.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.472465   843 fs_manager.cc:691] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
22:22:03.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.474630   858 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
22:22:03.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.475772   843 fs_manager.cc:725] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.475862   843 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data,/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/wal
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "152d9c4491be4c38845c27dba09653b4"
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.475927   843 fs_report.cc:383] FS layout report
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/wal
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/wal
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data/data
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
22:22:03.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
22:22:03.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
22:22:03.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
22:22:03.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
22:22:03.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
22:22:03.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
22:22:03.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
22:22:03.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.501382   843 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
22:22:03.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.504325   843 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
22:22:03.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.504480   843 kserver.cc:163] Server-wide thread pool size limit: 3276
22:22:03.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.511122   843 rpc_server.cc:307] RPC server started. Bound to: 127.0.205.62:38421
22:22:03.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.511408   910 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.0.205.62:38421 every 8 connection(s)
22:22:03.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.511976   843 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/data/info.pb
22:22:03.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.514429   820 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu as pid 843
22:22:03.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.514585   820 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/master-0/wal/instance
22:22:03.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.514941   911 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
22:22:03.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.521171   911 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4: Bootstrap starting.
22:22:03.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.523643   911 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4: Neither blocks nor log segments found. Creating new log.
22:22:03.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.524518   911 log.cc:824] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4: Log is configured to *not* fsync() on all Append() calls
22:22:03.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.526296   911 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4: No bootstrap required, opened a new log
22:22:03.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.530582   911 raft_consensus.cc:348] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "152d9c4491be4c38845c27dba09653b4" member_type: VOTER last_known_addr { host: "127.0.205.62" port: 38421 } }
22:22:03.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.530864   911 raft_consensus.cc:374] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
22:22:03.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.530912   911 raft_consensus.cc:729] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 152d9c4491be4c38845c27dba09653b4, State: Initialized, Role: FOLLOWER
22:22:03.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.531392   911 consensus_queue.cc:260] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [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: "152d9c4491be4c38845c27dba09653b4" member_type: VOTER last_known_addr { host: "127.0.205.62" port: 38421 } }
22:22:03.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.531536   911 raft_consensus.cc:388] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
22:22:03.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.531601   911 raft_consensus.cc:482] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
22:22:03.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.531713   911 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 0 FOLLOWER]: Advancing to term 1
22:22:03.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:03 dist-test-slave-5pk4 krb5kdc[830](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462923, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.0.205.62@KRBTEST.COM
22:22:03.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.532723   911 raft_consensus.cc:504] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "152d9c4491be4c38845c27dba09653b4" member_type: VOTER last_known_addr { host: "127.0.205.62" port: 38421 } }
22:22:03.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.533402   911 leader_election.cc:302] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [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: 152d9c4491be4c38845c27dba09653b4; no voters: 
22:22:03.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.534430   911 leader_election.cc:288] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [CANDIDATE]: Term 1 election: Requested vote from peers 
22:22:03.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.534806   917 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 1 FOLLOWER]: Leader election won for term 1
22:22:03.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.535342   917 raft_consensus.cc:686] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [term 1 LEADER]: Becoming Leader. State: Replica: 152d9c4491be4c38845c27dba09653b4, State: Running, Role: LEADER
22:22:03.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.535718   911 sys_catalog.cc:564] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [sys.catalog]: configured and running, proceeding with master startup.
22:22:03.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.535861   917 consensus_queue.cc:237] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [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: "152d9c4491be4c38845c27dba09653b4" member_type: VOTER last_known_addr { host: "127.0.205.62" port: 38421 } }
22:22:03.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.537220   917 sys_catalog.cc:455] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 152d9c4491be4c38845c27dba09653b4. Latest consensus state: current_term: 1 leader_uuid: "152d9c4491be4c38845c27dba09653b4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "152d9c4491be4c38845c27dba09653b4" member_type: VOTER last_known_addr { host: "127.0.205.62" port: 38421 } } }
22:22:03.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.537333   917 sys_catalog.cc:458] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [sys.catalog]: This master's current role is: LEADER
22:22:03.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.537941   916 sys_catalog.cc:455] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "152d9c4491be4c38845c27dba09653b4" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "152d9c4491be4c38845c27dba09653b4" member_type: VOTER last_known_addr { host: "127.0.205.62" port: 38421 } } }
22:22:03.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.538044   916 sys_catalog.cc:458] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4 [sys.catalog]: This master's current role is: LEADER
22:22:03.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.538916   927 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
22:22:03.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.539772   913 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.518471 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.0.1:38216 (local address 127.0.205.62:38421)
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.518894 (+   423us) server_negotiation.cc:206] Beginning negotiation
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.518913 (+    19us) server_negotiation.cc:399] Waiting for connection header
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.519789 (+   876us) server_negotiation.cc:407] Connection header received
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.521767 (+  1978us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.521782 (+    15us) server_negotiation.cc:461] Received NEGOTIATE request from client
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.522085 (+   303us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.522367 (+   282us) server_negotiation.cc:226] Negotiated authn=SASL
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.524958 (+  2591us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.526129 (+  1171us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.527761 (+  1632us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.528249 (+   488us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.528367 (+   118us) 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
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.533011 (+  4644us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
22:22:03.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.533018 (+     7us) server_negotiation.cc:881] Received SASL_INITIATE request from client
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.533033 (+    15us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.533064 (+    31us) server_negotiation.cc:922] calling sasl_server_start()
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.535347 (+  2283us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.536070 (+   723us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.536076 (+     6us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.536082 (+     6us) server_negotiation.cc:967] calling sasl_server_step()
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.536216 (+   134us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.536655 (+   439us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.536658 (+     3us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:03.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.536660 (+     2us) server_negotiation.cc:967] calling sasl_server_step()
22:22:03.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.537168 (+   508us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
22:22:03.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.537381 (+   213us) server_negotiation.cc:1035] Waiting for connection context
22:22:03.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.538478 (+  1097us) server_negotiation.cc:299] Negotiation successful
22:22:03.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.538711 (+   233us) negotiation.cc:327] Negotiation complete: OK
22:22:03.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":280,"thread_start_us":116,"threads_started":1}
22:22:03.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.542363   927 catalog_manager.cc:1484] Initializing Kudu cluster ID...
22:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.550727   927 catalog_manager.cc:1347] Generated new cluster ID: 2785f3c1b58c4bcd8d69721fa644c24f
22:22:03.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.550791   927 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
22:22:03.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.589591   927 catalog_manager.cc:1370] Generated new certificate authority record
22:22:03.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.590811   927 catalog_manager.cc:1504] Loading token signing keys...
22:22:03.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.600234   927 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 152d9c4491be4c38845c27dba09653b4: Generated new TSK 0
22:22:03.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.601066   927 catalog_manager.cc:1514] Initializing in-progress tserver states...
22:22:03.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.0.205.1@KRBTEST.COM; defaulting to no policy
22:22:03.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:03.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.0.205.1@KRBTEST.COM" created.
22:22:03.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:03.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.1.keytab.
22:22:03.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.1.keytab.
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.641115   820 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/wal
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/logs
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data/info.pb
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:03.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.1:0
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.0.205.1
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.1
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.0.205.62:38421
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.1.keytab
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.1
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
22:22:03.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
22:22:03.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.747570   937 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
22:22:03.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.747799   937 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
22:22:03.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.747853   937 flags.cc:425] Enabled unsafe flag: --never_fsync=true
22:22:03.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.752182   937 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
22:22:03.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.752235   937 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
22:22:03.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.752315   937 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.205.1
22:22:03.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.757622   937 tablet_server_runner.cc:78] Tablet server non-default flags:
22:22:03.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/wal
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.1.keytab
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.1
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.1:0
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:03.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data/info.pb
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.1
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.0.205.62:38421
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.937
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.0.205.1
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/logs
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 21:46:50 UTC on ccycloud.quasar-irklax.root.comops.site
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492326
22:22:03.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.758848   937 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
22:22:03.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.760079   937 file_cache.cc:493] Constructed file cache file cache with capacity 419430
22:22:03.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.768944   942 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
22:22:03.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.768970   945 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
22:22:03.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.768953   943 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
22:22:03.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.769243   937 server_base.cc:1034] running on GCE node
22:22:03.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.769838   937 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
22:22:03.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.770428   937 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
22:22:03.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.771584   937 hybrid_clock.cc:648] HybridClock initialized: now 1783462923771557 us; error 44 us; skew 500 ppm
22:22:03.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:03 dist-test-slave-5pk4 krb5kdc[830](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462923, etypes {rep=17 tkt=17 ses=17}, kudu/127.0.205.1@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
22:22:03.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.774307   937 init.cc:377] Logged in from keytab as kudu/127.0.205.1@KRBTEST.COM (short username kudu)
22:22:03.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.775071   937 webserver.cc:460] Webserver started at http://127.0.205.1:34551/ using document root <none> and password file <none>
22:22:03.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.775542   937 fs_manager.cc:359] Metadata directory not provided
22:22:03.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.775594   937 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
22:22:03.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.775745   937 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
22:22:03.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.777647   937 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data/instance:
22:22:03.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ba1630d491c743faac6227498e6f3a5d"
22:22:03.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.778113   937 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/wal/instance:
22:22:03.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ba1630d491c743faac6227498e6f3a5d"
22:22:03.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.782526   937 fs_manager.cc:691] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.000s
22:22:03.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.784588   952 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
22:22:03.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.785835   937 fs_manager.cc:725] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.785931   937 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data,/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/wal
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ba1630d491c743faac6227498e6f3a5d"
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.786001   937 fs_report.cc:383] FS layout report
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/wal
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/wal
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data/data
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
22:22:03.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
22:22:03.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.796672   937 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
22:22:03.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.799273   937 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
22:22:03.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.799405   937 kserver.cc:163] Server-wide thread pool size limit: 3276
22:22:03.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.799851   937 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
22:22:03.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.800539   937 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
22:22:03.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.800583   937 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
22:22:03.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.800619   937 ts_tablet_manager.cc:610] Registered 0 tablets
22:22:03.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.800632   937 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
22:22:03.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.808518   937 rpc_server.cc:307] RPC server started. Bound to: 127.0.205.1:41071
22:22:03.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.808555  1065 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.0.205.1:41071 every 8 connection(s)
22:22:03.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.809231   937 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/data/info.pb
22:22:03.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.817006   820 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu as pid 937
22:22:03.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.817145   820 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-0/wal/instance
22:22:03.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:03 dist-test-slave-5pk4 krb5kdc[830](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462923, etypes {rep=17 tkt=17 ses=17}, kudu/127.0.205.1@KRBTEST.COM for kudu/127.0.205.62@KRBTEST.COM
22:22:03.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.822223   913 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
22:22:03.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.810863 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.205.1:44561 (local address 127.0.205.62:38421)
22:22:03.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.811006 (+   143us) server_negotiation.cc:206] Beginning negotiation
22:22:03.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.811010 (+     4us) server_negotiation.cc:399] Waiting for connection header
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.811760 (+   750us) server_negotiation.cc:407] Connection header received
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.813046 (+  1286us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.813048 (+     2us) server_negotiation.cc:461] Received NEGOTIATE request from client
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.813103 (+    55us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.813182 (+    79us) server_negotiation.cc:226] Negotiated authn=SASL
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.814300 (+  1118us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.814821 (+   521us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.815939 (+  1118us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.816187 (+   248us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.816268 (+    81us) 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
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.818974 (+  2706us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.818977 (+     3us) server_negotiation.cc:881] Received SASL_INITIATE request from client
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.818980 (+     3us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.819007 (+    27us) server_negotiation.cc:922] calling sasl_server_start()
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820302 (+  1295us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820819 (+   517us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:03.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820823 (+     4us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820825 (+     2us) server_negotiation.cc:967] calling sasl_server_step()
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820910 (+    85us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821236 (+   326us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821239 (+     3us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821240 (+     1us) server_negotiation.cc:967] calling sasl_server_step()
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821401 (+   161us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821485 (+    84us) server_negotiation.cc:1035] Waiting for connection context
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.822006 (+   521us) server_negotiation.cc:299] Negotiation successful
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.822085 (+    79us) negotiation.cc:327] Negotiation complete: OK
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":54}
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.823158  1068 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.811207 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.0.205.62:38421 (local address 127.0.205.1:44561)
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.811623 (+   416us) negotiation.cc:107] Waiting for socket to connect
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.811661 (+    38us) client_negotiation.cc:174] Beginning negotiation
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.812837 (+  1176us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.813357 (+   520us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.813364 (+     7us) client_negotiation.cc:394] Received NEGOTIATE response from server
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.813698 (+   334us) client_negotiation.cc:189] Negotiated authn=SASL
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.814126 (+   428us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
22:22:03.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.814143 (+    17us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.814945 (+   802us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.814948 (+     3us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.815816 (+   868us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.815826 (+    10us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.816328 (+   502us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.816331 (+     3us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.816468 (+   137us) 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
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.817024 (+   556us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.817044 (+    20us) client_negotiation.cc:656] calling sasl_client_start()
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.818714 (+  1670us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
22:22:03.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820453 (+  1739us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
22:22:03.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820457 (+     4us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
22:22:03.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820467 (+    10us) client_negotiation.cc:760] calling sasl_client_step()
22:22:03.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.820710 (+   243us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
22:22:03.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821017 (+   307us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
22:22:03.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821019 (+     2us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
22:22:03.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821020 (+     1us) client_negotiation.cc:760] calling sasl_client_step()
22:22:03.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821135 (+   115us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
22:22:03.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821526 (+   391us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821531 (+     5us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821788 (+   257us) client_negotiation.cc:769] Sending connection context
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.821985 (+   197us) client_negotiation.cc:240] Negotiation successful
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:03.822213 (+   228us) negotiation.cc:327] Negotiation complete: OK
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":244,"thread_start_us":114,"threads_started":1}
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.0.205.2@KRBTEST.COM; defaulting to no policy
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.827572  1066 heartbeater.cc:347] Connected to a master server at 127.0.205.62:38421
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.827782  1066 heartbeater.cc:464] Registering TS with master...
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.0.205.2@KRBTEST.COM" created.
22:22:03.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.828299  1066 heartbeater.cc:511] Master 127.0.205.62:38421 requested a full tablet report, sending...
22:22:03.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.829985   875 ts_manager.cc:194] Registered new tserver with Master: ba1630d491c743faac6227498e6f3a5d (127.0.205.1:41071)
22:22:03.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.831522   875 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.0.205.1@KRBTEST.COM'} at 127.0.205.1:44561
22:22:03.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:03.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.2.keytab.
22:22:03.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.2.keytab.
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.841203   820 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/wal
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/logs
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data/info.pb
22:22:03.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.2:0
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.0.205.2
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.2
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.0.205.62:38421
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.2.keytab
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.2
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:03.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:03.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:03.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:03.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
22:22:03.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
22:22:03.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
22:22:03.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.951640  1071 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
22:22:03.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.951879  1071 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
22:22:03.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.951920  1071 flags.cc:425] Enabled unsafe flag: --never_fsync=true
22:22:03.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.955915  1071 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
22:22:03.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.955973  1071 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
22:22:03.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.956030  1071 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.205.2
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.961093  1071 tablet_server_runner.cc:78] Tablet server non-default flags:
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/wal
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.2.keytab
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.2
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.2:0
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data/info.pb
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.2
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.0.205.62:38421
22:22:03.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.1071
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.0.205.2
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/logs
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 21:46:50 UTC on ccycloud.quasar-irklax.root.comops.site
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492326
22:22:03.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.962018  1071 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
22:22:03.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.962992  1071 file_cache.cc:493] Constructed file cache file cache with capacity 419430
22:22:03.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.971815  1077 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
22:22:03.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.971813  1079 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
22:22:03.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:03.971820  1076 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
22:22:03.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.971863  1071 server_base.cc:1034] running on GCE node
22:22:03.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.972675  1071 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
22:22:03.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.973274  1071 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
22:22:03.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.974428  1071 hybrid_clock.cc:648] HybridClock initialized: now 1783462923974404 us; error 36 us; skew 500 ppm
22:22:03.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:03 dist-test-slave-5pk4 krb5kdc[830](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462923, etypes {rep=17 tkt=17 ses=17}, kudu/127.0.205.2@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
22:22:03.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.976948  1071 init.cc:377] Logged in from keytab as kudu/127.0.205.2@KRBTEST.COM (short username kudu)
22:22:03.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.977780  1071 webserver.cc:460] Webserver started at http://127.0.205.2:40897/ using document root <none> and password file <none>
22:22:03.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.978330  1071 fs_manager.cc:359] Metadata directory not provided
22:22:03.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.978399  1071 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
22:22:03.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.978638  1071 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
22:22:03.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.980438  1071 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data/instance:
22:22:03.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cc9be437143d4e7f908495bf360e3c0e"
22:22:03.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.981009  1071 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/wal/instance:
22:22:03.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cc9be437143d4e7f908495bf360e3c0e"
22:22:03.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.984522  1071 fs_manager.cc:691] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
22:22:03.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.986759  1086 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.988111  1071 fs_manager.cc:725] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.988220  1071 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data,/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/wal
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cc9be437143d4e7f908495bf360e3c0e"
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:03 on dist-test-slave-5pk4"
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:03.988317  1071 fs_report.cc:383] FS layout report
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/wal
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/wal
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data/data
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
22:22:03.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
22:22:03.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
22:22:03.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
22:22:03.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
22:22:03.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
22:22:04.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.003228  1071 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
22:22:04.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.006701  1071 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
22:22:04.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.006876  1071 kserver.cc:163] Server-wide thread pool size limit: 3276
22:22:04.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.007463  1071 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
22:22:04.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.008301  1071 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
22:22:04.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.008350  1071 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
22:22:04.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.008401  1071 ts_tablet_manager.cc:610] Registered 0 tablets
22:22:04.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.008422  1071 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
22:22:04.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.017406  1071 rpc_server.cc:307] RPC server started. Bound to: 127.0.205.2:46727
22:22:04.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.017436  1199 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.0.205.2:46727 every 8 connection(s)
22:22:04.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.018329  1071 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/data/info.pb
22:22:04.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.019737   820 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu as pid 1071
22:22:04.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.019845   820 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-1/wal/instance
22:22:04.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:04 dist-test-slave-5pk4 krb5kdc[830](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462923, etypes {rep=17 tkt=17 ses=17}, kudu/127.0.205.2@KRBTEST.COM for kudu/127.0.205.62@KRBTEST.COM
22:22:04.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.0.205.3@KRBTEST.COM; defaulting to no policy
22:22:04.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:04.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.0.205.3@KRBTEST.COM" created.
22:22:04.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.033699   913 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
22:22:04.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.020723 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.205.2:49305 (local address 127.0.205.62:38421)
22:22:04.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.020847 (+   124us) server_negotiation.cc:206] Beginning negotiation
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.020851 (+     4us) server_negotiation.cc:399] Waiting for connection header
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.021239 (+   388us) server_negotiation.cc:407] Connection header received
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.023012 (+  1773us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.023016 (+     4us) server_negotiation.cc:461] Received NEGOTIATE request from client
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.023067 (+    51us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.023158 (+    91us) server_negotiation.cc:226] Negotiated authn=SASL
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.024776 (+  1618us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.025327 (+   551us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.026646 (+  1319us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.026988 (+   342us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.027098 (+   110us) 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
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.029675 (+  2577us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.029678 (+     3us) server_negotiation.cc:881] Received SASL_INITIATE request from client
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.029680 (+     2us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.029702 (+    22us) server_negotiation.cc:922] calling sasl_server_start()
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.030995 (+  1293us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031619 (+   624us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031622 (+     3us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031625 (+     3us) server_negotiation.cc:967] calling sasl_server_step()
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031725 (+   100us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032364 (+   639us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032369 (+     5us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032371 (+     2us) server_negotiation.cc:967] calling sasl_server_step()
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032620 (+   249us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032717 (+    97us) server_negotiation.cc:1035] Waiting for connection context
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.033407 (+   690us) server_negotiation.cc:299] Negotiation successful
22:22:04.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.033528 (+   121us) negotiation.cc:327] Negotiation complete: OK
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"server-negotiator.queue_time_us":49}
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.034463  1202 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.020565 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.0.205.62:38421 (local address 127.0.205.2:49305)
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.021102 (+   537us) negotiation.cc:107] Waiting for socket to connect
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.021151 (+    49us) client_negotiation.cc:174] Beginning negotiation
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.022775 (+  1624us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.023450 (+   675us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.023460 (+    10us) client_negotiation.cc:394] Received NEGOTIATE response from server
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.024002 (+   542us) client_negotiation.cc:189] Negotiated authn=SASL
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.024616 (+   614us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.024647 (+    31us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.025468 (+   821us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
22:22:04.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.025472 (+     4us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.026532 (+  1060us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.026542 (+    10us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.027169 (+   627us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.027178 (+     9us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.027329 (+   151us) 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
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.027932 (+   603us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.027948 (+    16us) client_negotiation.cc:656] calling sasl_client_start()
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.029425 (+  1477us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031121 (+  1696us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031125 (+     4us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031138 (+    13us) client_negotiation.cc:760] calling sasl_client_step()
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031469 (+   331us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031847 (+   378us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031850 (+     3us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.031852 (+     2us) client_negotiation.cc:760] calling sasl_client_step()
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032020 (+   168us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032773 (+   753us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.032781 (+     8us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.033068 (+   287us) client_negotiation.cc:769] Sending connection context
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.033304 (+   236us) client_negotiation.cc:240] Negotiation successful
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.033476 (+   172us) negotiation.cc:327] Negotiation complete: OK
22:22:04.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":333,"thread_start_us":145,"threads_started":1}
22:22:04.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.035863  1200 heartbeater.cc:347] Connected to a master server at 127.0.205.62:38421
22:22:04.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.036073  1200 heartbeater.cc:464] Registering TS with master...
22:22:04.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.036681  1200 heartbeater.cc:511] Master 127.0.205.62:38421 requested a full tablet report, sending...
22:22:04.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.037735   875 ts_manager.cc:194] Registered new tserver with Master: cc9be437143d4e7f908495bf360e3c0e (127.0.205.2:46727)
22:22:04.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.038410   875 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.0.205.2@KRBTEST.COM'} at 127.0.205.2:49305
22:22:04.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
22:22:04.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.3.keytab.
22:22:04.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.0.205.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.3.keytab.
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.047381   820 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/wal
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/logs
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data/info.pb
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:04.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.3:0
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.0.205.3
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.3
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.0.205.62:38421
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.3.keytab
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.3
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
22:22:04.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
22:22:04.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:04.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:04.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:04.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:04.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-encryption=required
22:22:04.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections
22:22:04.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc-trace-negotiation with env {KRB5CCNAME=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
22:22:04.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.158119  1205 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
22:22:04.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.158381  1205 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
22:22:04.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.158437  1205 flags.cc:425] Enabled unsafe flag: --never_fsync=true
22:22:04.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.163098  1205 flags.cc:425] Enabled experimental flag: --rpc_trace_negotiation=true
22:22:04.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.163158  1205 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
22:22:04.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.163219  1205 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.0.205.3
22:22:04.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.168787  1205 tablet_server_runner.cc:78] Tablet server non-default flags:
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.0.205.20:37277
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/wal
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encrypt_loopback_connections=true
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_trace_negotiation=true
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/kudu_127.0.205.3.keytab
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.0.205.3
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.0.205.3:0
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_encryption=required
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data/info.pb
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.0.205.3
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.0.205.62:38421
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
22:22:04.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.1205
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.0.205.3
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/logs
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 07 Jul 2026 21:46:50 UTC on ccycloud.quasar-irklax.root.comops.site
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492326
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.169750  1205 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
22:22:04.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.170795  1205 file_cache.cc:493] Constructed file cache file cache with capacity 419430
22:22:04.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.182806  1210 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
22:22:04.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.182881  1211 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
22:22:04.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.182875  1213 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
22:22:04.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.183187  1205 server_base.cc:1034] running on GCE node
22:22:04.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.183599  1205 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
22:22:04.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.184342  1205 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
22:22:04.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.185519  1205 hybrid_clock.cc:648] HybridClock initialized: now 1783462924185499 us; error 35 us; skew 500 ppm
22:22:04.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:04 dist-test-slave-5pk4 krb5kdc[830](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462924, etypes {rep=17 tkt=17 ses=17}, kudu/127.0.205.3@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
22:22:04.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.188609  1205 init.cc:377] Logged in from keytab as kudu/127.0.205.3@KRBTEST.COM (short username kudu)
22:22:04.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.189445  1205 webserver.cc:460] Webserver started at http://127.0.205.3:33799/ using document root <none> and password file <none>
22:22:04.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.189990  1205 fs_manager.cc:359] Metadata directory not provided
22:22:04.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.190059  1205 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
22:22:04.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.190305  1205 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
22:22:04.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.192304  1205 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data/instance:
22:22:04.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "32d3f4ef1e584646b3cdcf4288a21679"
22:22:04.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:04 on dist-test-slave-5pk4"
22:22:04.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.192874  1205 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/wal/instance:
22:22:04.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "32d3f4ef1e584646b3cdcf4288a21679"
22:22:04.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:04 on dist-test-slave-5pk4"
22:22:04.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.196503  1205 fs_manager.cc:691] Time spent creating directory manager: real 0.003s	user 0.005s	sys 0.000s
22:22:04.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.198876  1220 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
22:22:04.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.200613  1205 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
22:22:04.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.200723  1205 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data,/tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/wal
22:22:04.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "32d3f4ef1e584646b3cdcf4288a21679"
22:22:04.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 22:22:04 on dist-test-slave-5pk4"
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.200824  1205 fs_report.cc:383] FS layout report
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/wal
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/wal
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data/data
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
22:22:04.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
22:22:04.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.221745  1205 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
22:22:04.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.224677  1205 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
22:22:04.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.224876  1205 kserver.cc:163] Server-wide thread pool size limit: 3276
22:22:04.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.225476  1205 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
22:22:04.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.226354  1205 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
22:22:04.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.226406  1205 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
22:22:04.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.226459  1205 ts_tablet_manager.cc:610] Registered 0 tablets
22:22:04.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.226480  1205 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
22:22:04.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.236009  1205 rpc_server.cc:307] RPC server started. Bound to: 127.0.205.3:38625
22:22:04.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.236030  1333 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.0.205.3:38625 every 8 connection(s)
22:22:04.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.236941  1205 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/data/info.pb
22:22:04.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.244005   820 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu as pid 1205
22:22:04.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.244117   820 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/ts-2/wal/instance
22:22:04.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 07 22:22:04 dist-test-slave-5pk4 krb5kdc[830](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783462924, etypes {rep=17 tkt=17 ses=17}, kudu/127.0.205.3@KRBTEST.COM for kudu/127.0.205.62@KRBTEST.COM
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.251955   913 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.238855 (+     0us) reactor.cc:625] Submitting negotiation task for server connection from 127.0.205.3:54805 (local address 127.0.205.62:38421)
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.239014 (+   159us) server_negotiation.cc:206] Beginning negotiation
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.239019 (+     5us) server_negotiation.cc:399] Waiting for connection header
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.240209 (+  1190us) server_negotiation.cc:407] Connection header received
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.241521 (+  1312us) server_negotiation.cc:365] Received NEGOTIATE NegotiatePB request
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.241524 (+     3us) server_negotiation.cc:461] Received NEGOTIATE request from client
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.241569 (+    45us) server_negotiation.cc:377] Sending NEGOTIATE NegotiatePB response
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.241646 (+    77us) server_negotiation.cc:226] Negotiated authn=SASL
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.242782 (+  1136us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.243331 (+   549us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.244909 (+  1578us) server_negotiation.cc:365] Received TLS_HANDSHAKE NegotiatePB request
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.245327 (+   418us) server_negotiation.cc:377] Sending TLS_HANDSHAKE NegotiatePB response
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.245445 (+   118us) 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
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.248367 (+  2922us) server_negotiation.cc:365] Received SASL_INITIATE NegotiatePB request
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.248370 (+     3us) server_negotiation.cc:881] Received SASL_INITIATE request from client
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.248372 (+     2us) server_negotiation.cc:892] Client requested to use mechanism: GSSAPI
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.248403 (+    31us) server_negotiation.cc:922] calling sasl_server_start()
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.249902 (+  1499us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250521 (+   619us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250524 (+     3us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250525 (+     1us) server_negotiation.cc:967] calling sasl_server_step()
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250621 (+    96us) server_negotiation.cc:377] Sending SASL_CHALLENGE NegotiatePB response
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250977 (+   356us) server_negotiation.cc:365] Received SASL_RESPONSE NegotiatePB request
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250980 (+     3us) server_negotiation.cc:956] Received SASL_RESPONSE request from client
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250981 (+     1us) server_negotiation.cc:967] calling sasl_server_step()
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251185 (+   204us) server_negotiation.cc:377] Sending SASL_SUCCESS NegotiatePB response
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251276 (+    91us) server_negotiation.cc:1035] Waiting for connection context
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251756 (+   480us) server_negotiation.cc:299] Negotiation successful
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251834 (+    78us) negotiation.cc:327] Negotiation complete: OK
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"mutex_wait_us":1,"server-negotiator.queue_time_us":57}
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.252638  1336 negotiation.cc:339] RPC negotiation tracing enabled. Trace:
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.239241 (+     0us) reactor.cc:625] Submitting negotiation task for client connection to 127.0.205.62:38421 (local address 127.0.205.3:54805)
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.240064 (+   823us) negotiation.cc:107] Waiting for socket to connect
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.240104 (+    40us) client_negotiation.cc:174] Beginning negotiation
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.241328 (+  1224us) client_negotiation.cc:252] Sending NEGOTIATE NegotiatePB request
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.241787 (+   459us) client_negotiation.cc:271] Received NEGOTIATE NegotiatePB response
22:22:04.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.241793 (+     6us) client_negotiation.cc:394] Received NEGOTIATE response from server
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.242130 (+   337us) client_negotiation.cc:189] Negotiated authn=SASL
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.242641 (+   511us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.242655 (+    14us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.243442 (+   787us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.243445 (+     3us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.244686 (+  1241us) client_negotiation.cc:514] Sending TLS_HANDSHAKE message to server
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.244697 (+    11us) client_negotiation.cc:252] Sending TLS_HANDSHAKE NegotiatePB request
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.245510 (+   813us) client_negotiation.cc:271] Received TLS_HANDSHAKE NegotiatePB response
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.245514 (+     4us) client_negotiation.cc:527] Received TLS_HANDSHAKE response from server
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.245665 (+   151us) 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
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.246676 (+  1011us) client_negotiation.cc:623] Initiating SASL GSSAPI handshake
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.246699 (+    23us) client_negotiation.cc:656] calling sasl_client_start()
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.248120 (+  1421us) client_negotiation.cc:252] Sending SASL_INITIATE NegotiatePB request
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250052 (+  1932us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250056 (+     4us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250068 (+    12us) client_negotiation.cc:760] calling sasl_client_step()
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250376 (+   308us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250715 (+   339us) client_negotiation.cc:271] Received SASL_CHALLENGE NegotiatePB response
22:22:04.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250717 (+     2us) client_negotiation.cc:694] Received SASL_CHALLENGE response from server
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250718 (+     1us) client_negotiation.cc:760] calling sasl_client_step()
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.250837 (+   119us) client_negotiation.cc:252] Sending SASL_RESPONSE NegotiatePB request
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251318 (+   481us) client_negotiation.cc:271] Received SASL_SUCCESS NegotiatePB response
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251322 (+     4us) client_negotiation.cc:715] Received SASL_SUCCESS response from server
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251538 (+   216us) client_negotiation.cc:769] Sending connection context
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251704 (+   166us) client_negotiation.cc:240] Negotiation successful
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 0707 22:22:04.251862 (+   158us) negotiation.cc:327] Negotiation complete: OK
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Metrics: {"client-negotiator.queue_time_us":645,"thread_start_us":110,"threads_started":1}
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.253733  1334 heartbeater.cc:347] Connected to a master server at 127.0.205.62:38421
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.253959  1334 heartbeater.cc:464] Registering TS with master...
22:22:04.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.254492  1334 heartbeater.cc:511] Master 127.0.205.62:38421 requested a full tablet report, sending...
22:22:04.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.255662   875 ts_manager.cc:194] Registered new tserver with Master: 32d3f4ef1e584646b3cdcf4288a21679 (127.0.205.3:38625)
22:22:04.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.256310   875 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.0.205.3@KRBTEST.COM'} at 127.0.205.3:54805
22:22:04.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.259677   820 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
22:22:04.260 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
22:22:04.261 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

22:22:04.270 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

22:22:04.279 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.0.205.62"
      port: 38421
    }
    bound_http_address {
      host: "127.0.205.62"
      port: 33423
    }
  }
}

22:22:04.282 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

22:22:04.284 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.0.205.1"
      port: 41071
    }
    bound_http_address {
      host: "127.0.205.1"
      port: 34551
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.0.205.2"
      port: 46727
    }
    bound_http_address {
      host: "127.0.205.2"
      port: 40897
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.0.205.3"
      port: 38625
    }
    bound_http_address {
      host: "127.0.205.3"
      port: 33799
    }
  }
}

22:22:04.284 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
22:22:04.503 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskH0iKQE/test-tmp/mini-kudu-cluster8464244189159433519/krb5kdc/krb5cc
22:22:04.513 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
22:22:04.527 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
22:22:04.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.531569   820 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu with pid 937
22:22:04.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.539477   860 connection.cc:535] server connection from 127.0.205.1:44561 recv error: Network error: failed to read from TLS socket (remote: 127.0.205.1:44561): Connection reset by peer (error 104)
22:22:04.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.539680   820 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu with pid 1071
22:22:04.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.544384   862 connection.cc:535] server connection from 127.0.205.2:49305 recv error: Network error: failed to read from TLS socket (remote: 127.0.205.2:49305): Connection reset by peer (error 104)
22:22:04.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.544569   820 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu with pid 1205
22:22:04.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.549017   862 connection.cc:535] server connection from 127.0.205.3:54805 recv error: Network error: failed to read from TLS socket (remote: 127.0.205.3:54805): Connection reset by peer (error 104)
22:22:04.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 22:22:04.549302   820 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskH0iKQE/build/debug/bin/kudu with pid 843
22:22:04.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 22:22:04.554157   828 connection.cc:535] client connection to 127.0.205.62:38421 recv error: Network error: failed to read from TLS socket (remote: 127.0.205.62:38421): Connection reset by peer (error 104)
22:22:04.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T22:22:04Z chronyd exiting

Time: 3.444

OK (1 test)

