JUnit version 4.13.2
.02:11:45.640 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1783476705631
02:11:45.668 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
02:11:45.700 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
02:11:45.707 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskljtXwN/test-tmp
02:11:45.730 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
02:11:45.732 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskljtXwN/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
02:11:45.937 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518"
  extra_master_flags: "--webserver_require_spnego=false"
  extra_master_flags: "--authn_token_validity_seconds=1"
  extra_master_flags: "--rpc_default_keepalive_time_ms=120000"
  extra_tserver_flags: "--webserver_require_spnego=false"
  extra_tserver_flags: "--rpc_default_keepalive_time_ms=120000"
  extra_tserver_flags: "--rpc_inject_invalid_authn_token_ratio=0.5"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

02:11:46.142 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
02:11:46.144 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

02:11:46.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:46.150022 31064 mini_oidc.cc:150] Starting JWKS server
02:11:46.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:46.154744 31064 webserver.cc:534] Webserver started at http://127.0.0.1:44681/ using document root <none> and password file <none>
02:11:46.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:46.155403 31064 mini_oidc.cc:186] Starting OIDC Discovery server
02:11:46.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:46.157732 31064 webserver.cc:534] Webserver started at http://127.0.0.1:34523/ using document root <none> and password file <none>
02:11:46.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
02:11:46.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/principal' for realm 'KRBTEST.COM',
02:11:46.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
02:11:46.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:46 dist-test-slave-r9r8 krb5kdc[31075](info): setting up network...
02:11:46.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
02:11:46.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:46 dist-test-slave-r9r8 krb5kdc[31075](info): set up 2 sockets
02:11:46.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:46 dist-test-slave-r9r8 krb5kdc[31075](info): commencing operation
02:11:46.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
02:11:48.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.066202 31064 mini_kdc.cc:121] Time spent starting KDC: real 1.872s	user 0.005s	sys 0.016s
02:11:48.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
02:11:48.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:11:48.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
02:11:48.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
02:11:48.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:11:48.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
02:11:48.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
02:11:48.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:11:48.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
02:11:48.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:11:48.170 [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-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/test-user.keytab.
02:11:48.171 [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-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/test-user.keytab.
02:11:48.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:48 dist-test-slave-r9r8 krb5kdc[31075](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476708, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:11:48.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
02:11:48.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T02:11:48Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
02:11:48.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T02:11:48Z Disabled control of system clock
02:11:48.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.86.62@KRBTEST.COM; defaulting to no policy
02:11:48.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:48.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.86.62@KRBTEST.COM" created.
02:11:48.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:48.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:48.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:48.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.30.86.62@KRBTEST.COM; defaulting to no policy
02:11:48.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:48.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.30.86.62@KRBTEST.COM" created.
02:11:48.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:48.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:48.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:48.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:48.300727 31064 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:48.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:48.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/wal
02:11:48.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data
02:11:48.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:11:48.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:11:48.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:11:48.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:48.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:48.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:48.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/logs
02:11:48.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data/info.pb
02:11:48.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:48.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:11:48.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:11:48.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:11:48.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:11:48.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:48.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:48.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:48.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
02:11:48.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:11:48.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
02:11:48.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
02:11:48.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.62:41415
02:11:48.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.62
02:11:48.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:48.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:48.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.62
02:11:48.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:48.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:11:48.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:11:48.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:11:48.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:48.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:48.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:48.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:48.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
02:11:48.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.30.86.62:41415
02:11:48.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
02:11:48.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44681/jwks.json
02:11:48.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:11:48.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
02:11:48.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:11:48.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.617272 31091 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:11:48.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.617909 31091 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:11:48.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.618515 31091 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:11:48.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.652385 31091 flags.cc:432] Enabled experimental flag: --authn_token_validity_seconds=1
02:11:48.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.652666 31091 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
02:11:48.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.652969 31091 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:11:48.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.653191 31091 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
02:11:48.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.653399 31091 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
02:11:48.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.653618 31091 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
02:11:48.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.653775 31091 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:44681/jwks.json
02:11:48.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:48.694312 31091 master_runner.cc:387] Master server non-default flags:
02:11:48.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:48.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:48.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:48.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:48.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data
02:11:48.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/wal
02:11:48.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
02:11:48.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
02:11:48.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.30.86.62:41415
02:11:48.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:48.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.62
02:11:48.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:48.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:48.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
02:11:48.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.62:41415
02:11:48.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
02:11:48.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:11:48.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
02:11:48.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:44681/jwks.json
02:11:48.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:48.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:11:48.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:11:48.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:11:48.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:48.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:48.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data/info.pb
02:11:48.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.62
02:11:48.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:48.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:11:48.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:48.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:11:48.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:11:48.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:48.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/logs
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision a3f35afc2b6dad6aa403ec58378221544a26edd0
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 01:40:41 UTC on e7f111948823
02:11:48.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16850
02:11:48.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:11:48.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:48.695952 31091 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:11:48.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:48.697248 31091 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:11:48.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.710072 31097 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
02:11:49.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.710887 31098 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
02:11:49.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:48.715452 31100 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
02:11:49.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:48.715278 31091 server_base.cc:1061] running on GCE node
02:11:49.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.834411 31091 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:11:49.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.837512 31091 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:11:49.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.838918 31091 hybrid_clock.cc:648] HybridClock initialized: now 1783476709838877 us; error 58 us; skew 500 ppm
02:11:49.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.839983 31091 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:11:49.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:49 dist-test-slave-r9r8 krb5kdc[31075](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476709, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.86.62@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:11:49.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.846477 31091 init.cc:377] Logged in from keytab as kudu/127.30.86.62@KRBTEST.COM (short username kudu)
02:11:49.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.851905 31091 webserver.cc:534] Webserver started at http://127.30.86.62:36951/ using document root <none> and password file <none>
02:11:49.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.853082 31091 fs_manager.cc:362] Metadata directory not provided
02:11:49.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.853345 31091 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:11:49.854 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.853986 31091 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:11:49.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.858705 31091 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data/instance:
02:11:49.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "166034d2dbbf4edeb36de2fef08b0d0f"
02:11:49.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:49 on dist-test-slave-r9r8"
02:11:49.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.860335 31091 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/wal/instance:
02:11:49.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "166034d2dbbf4edeb36de2fef08b0d0f"
02:11:49.861 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:49 on dist-test-slave-r9r8"
02:11:49.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.868698 31091 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.007s	sys 0.001s
02:11:49.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.874893 31108 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:11:49.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.876250 31091 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
02:11:49.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.876644 31091 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data,/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/wal
02:11:49.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "166034d2dbbf4edeb36de2fef08b0d0f"
02:11:49.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:49 on dist-test-slave-r9r8"
02:11:49.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.877094 31091 fs_report.cc:389] FS layout report
02:11:49.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:11:49.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/wal
02:11:49.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/wal
02:11:49.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data/data
02:11:49.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:11:49.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:11:49.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:11:49.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:11:49.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:11:49.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:11:49.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:11:49.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:11:49.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:11:49.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:11:49.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:11:49.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.940344 31091 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:11:49.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.946555 31091 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:11:49.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:49.947136 31091 kserver.cc:163] Server-wide thread pool size limit: 3276
02:11:50.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.018756 31091 rpc_server.cc:307] RPC server started. Bound to: 127.30.86.62:41415
02:11:50.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.018838 31159 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.86.62:41415 every 8 connection(s)
02:11:50.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.021935 31091 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/data/info.pb
02:11:50.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.023950 31064 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu as pid 31091
02:11:50.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.024533 31064 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/master-0/wal/instance
02:11:50.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.031839 31160 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
02:11:50.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.063412 31160 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f: Bootstrap starting.
02:11:50.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:50 dist-test-slave-r9r8 krb5kdc[31075](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476708, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.30.86.62@KRBTEST.COM
02:11:50.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.071163 31160 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f: Neither blocks nor log segments found. Creating new log.
02:11:50.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.073143 31160 log.cc:826] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f: Log is configured to *not* fsync() on all Append() calls
02:11:50.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.078781 31160 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f: No bootstrap required, opened a new log
02:11:50.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.099466 31160 raft_consensus.cc:359] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "166034d2dbbf4edeb36de2fef08b0d0f" member_type: VOTER last_known_addr { host: "127.30.86.62" port: 41415 } }
02:11:50.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.100203 31160 raft_consensus.cc:385] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:11:50.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.100435 31160 raft_consensus.cc:740] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 166034d2dbbf4edeb36de2fef08b0d0f, State: Initialized, Role: FOLLOWER
02:11:50.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.101460 31160 consensus_queue.cc:260] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [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: "166034d2dbbf4edeb36de2fef08b0d0f" member_type: VOTER last_known_addr { host: "127.30.86.62" port: 41415 } }
02:11:50.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.102018 31160 raft_consensus.cc:399] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
02:11:50.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.102272 31160 raft_consensus.cc:493] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
02:11:50.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.102562 31160 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 0 FOLLOWER]: Advancing to term 1
02:11:50.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.107313 31160 raft_consensus.cc:515] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "166034d2dbbf4edeb36de2fef08b0d0f" member_type: VOTER last_known_addr { host: "127.30.86.62" port: 41415 } }
02:11:50.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.108052 31160 leader_election.cc:304] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [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: 166034d2dbbf4edeb36de2fef08b0d0f; no voters: 
02:11:50.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.109814 31160 leader_election.cc:290] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [CANDIDATE]: Term 1 election: Requested vote from peers 
02:11:50.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.110157 31165 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 1 FOLLOWER]: Leader election won for term 1
02:11:50.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.114120 31165 raft_consensus.cc:697] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [term 1 LEADER]: Becoming Leader. State: Replica: 166034d2dbbf4edeb36de2fef08b0d0f, State: Running, Role: LEADER
02:11:50.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.114342 31160 sys_catalog.cc:565] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [sys.catalog]: configured and running, proceeding with master startup.
02:11:50.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.115150 31165 consensus_queue.cc:237] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [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: "166034d2dbbf4edeb36de2fef08b0d0f" member_type: VOTER last_known_addr { host: "127.30.86.62" port: 41415 } }
02:11:50.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.126549 31166 sys_catalog.cc:455] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "166034d2dbbf4edeb36de2fef08b0d0f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "166034d2dbbf4edeb36de2fef08b0d0f" member_type: VOTER last_known_addr { host: "127.30.86.62" port: 41415 } } }
02:11:50.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.127480 31166 sys_catalog.cc:458] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [sys.catalog]: This master's current role is: LEADER
02:11:50.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.126678 31167 sys_catalog.cc:455] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [sys.catalog]: SysCatalogTable state changed. Reason: New leader 166034d2dbbf4edeb36de2fef08b0d0f. Latest consensus state: current_term: 1 leader_uuid: "166034d2dbbf4edeb36de2fef08b0d0f" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "166034d2dbbf4edeb36de2fef08b0d0f" member_type: VOTER last_known_addr { host: "127.30.86.62" port: 41415 } } }
02:11:50.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.129880 31167 sys_catalog.cc:458] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f [sys.catalog]: This master's current role is: LEADER
02:11:50.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.137935 31175 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
02:11:50.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.154645 31175 catalog_manager.cc:1519] Initializing Kudu cluster ID...
02:11:50.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.181043 31175 catalog_manager.cc:1382] Generated new cluster ID: 1f7c9db3cda44d53a1e1d9f49b31ac1b
02:11:50.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.181380 31175 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
02:11:50.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.206935 31175 catalog_manager.cc:1405] Generated new certificate authority record
02:11:50.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.209244 31175 catalog_manager.cc:1539] Loading token signing keys...
02:11:50.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.230000 31175 catalog_manager.cc:6069] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f: Generated new TSK 0
02:11:50.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.231221 31175 catalog_manager.cc:1549] Initializing in-progress tserver states...
02:11:50.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.86.1@KRBTEST.COM; defaulting to no policy
02:11:50.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:50.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.86.1@KRBTEST.COM" created.
02:11:50.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:50.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:50.290 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:50.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.30.86.1@KRBTEST.COM; defaulting to no policy
02:11:50.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:50.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.30.86.1@KRBTEST.COM" created.
02:11:50.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:50.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:50.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:50.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.331751 31064 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:50.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:50.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/wal
02:11:50.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data
02:11:50.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:11:50.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:11:50.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:11:50.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:50.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:50.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:50.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/logs
02:11:50.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data/info.pb
02:11:50.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:50.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:11:50.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:11:50.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:11:50.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:11:50.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:50.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:50.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:50.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
02:11:50.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:11:50.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.1:0
02:11:50.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.86.1
02:11:50.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.1
02:11:50.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:50.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.86.62:41415
02:11:50.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:50.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.1
02:11:50.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:50.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:11:50.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:11:50.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:11:50.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:50.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:50.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:50.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:50.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:11:50.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:11:50.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:11:50.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.675951 31188 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
02:11:50.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.676687 31188 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:11:50.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.677037 31188 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:11:50.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.677965 31188 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:11:50.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.712893 31188 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:11:50.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.714244 31188 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.30.86.1
02:11:50.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.754643 31188 tablet_server_runner.cc:78] Tablet server non-default flags:
02:11:50.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:50.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:50.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:50.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:50.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data
02:11:50.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/wal
02:11:50.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
02:11:50.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:50.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.1
02:11:50.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:50.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:50.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.1:0
02:11:50.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:11:50.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:50.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:11:50.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:11:50.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:11:50.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:50.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:50.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data/info.pb
02:11:50.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.1
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.86.62:41415
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:50.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.86.1
02:11:50.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/logs
02:11:50.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:50.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:11:50.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:11:50.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
02:11:50.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:11:50.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision a3f35afc2b6dad6aa403ec58378221544a26edd0
02:11:50.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:11:50.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 01:40:41 UTC on e7f111948823
02:11:50.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16850
02:11:50.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:11:50.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.756606 31188 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:11:50.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:50.758396 31188 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:11:50.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.776221 31194 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
02:11:52.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:52.178170 31193 debug-util.cc:398] Leaking SignalData structure 0x7b080002eee0 after lost signal to thread 31188
02:11:52.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:52.245899 31193 kernel_stack_watchdog.cc:198] Thread 31188 stuck at /home/jenkins-slave/workspace/build_and_test@2/src/kudu/util/thread.cc:642 for 399ms:
02:11:52.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
02:11:52.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
02:11:52.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
02:11:52.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
02:11:52.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:50.777137 31195 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
02:11:52.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:52.249378 31197 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
02:11:52.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:52.248595 31188 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.471s	user 0.459s	sys 0.971s
02:11:52.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:52.250674 31188 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.474s	user 0.460s	sys 0.971s
02:11:52.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:52.251740 31196 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1473 milliseconds
02:11:52.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.251857 31188 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
02:11:52.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.254125 31188 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:11:52.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.256897 31188 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:11:52.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.258739 31188 hybrid_clock.cc:648] HybridClock initialized: now 1783476712258701 us; error 44 us; skew 500 ppm
02:11:52.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.259907 31188 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:11:52.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:52 dist-test-slave-r9r8 krb5kdc[31075](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476712, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.86.1@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:11:52.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.267953 31188 init.cc:377] Logged in from keytab as kudu/127.30.86.1@KRBTEST.COM (short username kudu)
02:11:52.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.273816 31188 webserver.cc:534] Webserver started at http://127.30.86.1:39607/ using document root <none> and password file <none>
02:11:52.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.275097 31188 fs_manager.cc:362] Metadata directory not provided
02:11:52.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.275403 31188 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:11:52.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.276005 31188 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:11:52.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.282567 31188 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data/instance:
02:11:52.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "60bfd4e17c40476d94b561e41458ce9b"
02:11:52.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:52 on dist-test-slave-r9r8"
02:11:52.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.284307 31188 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/wal/instance:
02:11:52.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "60bfd4e17c40476d94b561e41458ce9b"
02:11:52.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:52 on dist-test-slave-r9r8"
02:11:52.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.294600 31188 fs_manager.cc:696] Time spent creating directory manager: real 0.009s	user 0.010s	sys 0.000s
02:11:52.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.302126 31205 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:11:52.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.303400 31188 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
02:11:52.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.303761 31188 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data,/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/wal
02:11:52.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "60bfd4e17c40476d94b561e41458ce9b"
02:11:52.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:52 on dist-test-slave-r9r8"
02:11:52.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.304073 31188 fs_report.cc:389] FS layout report
02:11:52.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:11:52.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/wal
02:11:52.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/wal
02:11:52.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data/data
02:11:52.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:11:52.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:11:52.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:11:52.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:11:52.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:11:52.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:11:52.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:11:52.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:11:52.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:11:52.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:11:52.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:11:52.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.392122 31188 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:11:52.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.403039 31188 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:11:52.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.403556 31188 kserver.cc:163] Server-wide thread pool size limit: 3276
02:11:52.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.406677 31188 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
02:11:52.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.411242 31188 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
02:11:52.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.411458 31188 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
02:11:52.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.411660 31188 ts_tablet_manager.cc:616] Registered 0 tablets
02:11:52.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.411787 31188 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
02:11:52.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.618643 31188 rpc_server.cc:307] RPC server started. Bound to: 127.30.86.1:41023
02:11:52.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.618770 31317 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.86.1:41023 every 8 connection(s)
02:11:52.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.621567 31188 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/data/info.pb
02:11:52.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.622943 31064 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu as pid 31188
02:11:52.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.623448 31064 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-0/wal/instance
02:11:52.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:52 dist-test-slave-r9r8 krb5kdc[31075](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476712, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.86.1@KRBTEST.COM for kudu/127.30.86.62@KRBTEST.COM
02:11:52.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.86.2@KRBTEST.COM; defaulting to no policy
02:11:52.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:52.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.86.2@KRBTEST.COM" created.
02:11:52.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.677912 31318 heartbeater.cc:344] Connected to a master server at 127.30.86.62:41415
02:11:52.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.678404 31318 heartbeater.cc:461] Registering TS with master...
02:11:52.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.679733 31318 heartbeater.cc:507] Master 127.30.86.62:41415 requested a full tablet report, sending...
02:11:52.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.682757 31125 ts_manager.cc:194] Registered new tserver with Master: 60bfd4e17c40476d94b561e41458ce9b (127.30.86.1:41023)
02:11:52.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.684913 31125 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.30.86.1@KRBTEST.COM'} at 127.30.86.1:60535
02:11:52.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:52.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:52.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:52.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.30.86.2@KRBTEST.COM; defaulting to no policy
02:11:52.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:52.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.30.86.2@KRBTEST.COM" created.
02:11:52.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:52.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:52.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:52.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:52.743818 31064 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:52.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:52.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal
02:11:52.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/logs
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data/info.pb
02:11:52.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:11:52.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.2:0
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.86.2
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.2
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.86.62:41415
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.2
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:11:52.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:11:52.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:11:53.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.075588 31326 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
02:11:53.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.076254 31326 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:11:53.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.076478 31326 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:11:53.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.077045 31326 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:11:53.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.110745 31326 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:11:53.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.111770 31326 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.30.86.2
02:11:53.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:53.153328 31326 tablet_server_runner.cc:78] Tablet server non-default flags:
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.2
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.2:0
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:11:53.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data/info.pb
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.2
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.86.62:41415
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:11:53.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.86.2
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/logs
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:11:53.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision a3f35afc2b6dad6aa403ec58378221544a26edd0
02:11:53.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:11:53.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 01:40:41 UTC on e7f111948823
02:11:53.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16850
02:11:53.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:11:53.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:53.154683 31326 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:11:53.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:53.155798 31326 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:11:53.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.169831 31332 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
02:11:53.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:53.689277 31318 heartbeater.cc:499] Master 127.30.86.62:41415 was elected leader, sending a full tablet report...
02:11:54.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.172833 31333 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
02:11:54.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:53.173818 31335 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
02:11:54.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:53.174036 31326 server_base.cc:1061] running on GCE node
02:11:54.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.266352 31326 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:11:54.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.269395 31326 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:11:54.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.270874 31326 hybrid_clock.cc:648] HybridClock initialized: now 1783476714270825 us; error 67 us; skew 500 ppm
02:11:54.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.271914 31326 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:11:54.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:54 dist-test-slave-r9r8 krb5kdc[31075](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476714, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.86.2@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:11:54.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.278355 31326 init.cc:377] Logged in from keytab as kudu/127.30.86.2@KRBTEST.COM (short username kudu)
02:11:54.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.283638 31326 webserver.cc:534] Webserver started at http://127.30.86.2:40779/ using document root <none> and password file <none>
02:11:54.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.284795 31326 fs_manager.cc:362] Metadata directory not provided
02:11:54.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.285037 31326 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:11:54.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.285663 31326 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:11:54.290 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.290171 31326 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data/instance:
02:11:54.290 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "43c4a191060f49709b9b75e3937f7192"
02:11:54.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:54 on dist-test-slave-r9r8"
02:11:54.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.291589 31326 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal/instance:
02:11:54.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "43c4a191060f49709b9b75e3937f7192"
02:11:54.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:54 on dist-test-slave-r9r8"
02:11:54.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.299289 31326 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.007s	sys 0.001s
02:11:54.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.304906 31343 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:11:54.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.306042 31326 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.000s
02:11:54.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.306412 31326 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data,/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal
02:11:54.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "43c4a191060f49709b9b75e3937f7192"
02:11:54.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:54 on dist-test-slave-r9r8"
02:11:54.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.306807 31326 fs_report.cc:389] FS layout report
02:11:54.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:11:54.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal
02:11:54.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal
02:11:54.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data/data
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:11:54.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:11:54.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.367832 31326 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:11:54.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.374445 31326 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:11:54.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.374974 31326 kserver.cc:163] Server-wide thread pool size limit: 3276
02:11:54.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.378623 31326 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
02:11:54.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.383477 31326 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
02:11:54.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.383706 31326 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
02:11:54.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.383991 31326 ts_tablet_manager.cc:616] Registered 0 tablets
02:11:54.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.384207 31326 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
02:11:54.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.535677 31326 rpc_server.cc:307] RPC server started. Bound to: 127.30.86.2:42405
02:11:54.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.535789 31455 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.86.2:42405 every 8 connection(s)
02:11:54.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.538940 31326 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/data/info.pb
02:11:54.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.541548 31064 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu as pid 31326
02:11:54.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.542038 31064 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal/instance
02:11:54.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:54 dist-test-slave-r9r8 krb5kdc[31075](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476714, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.86.2@KRBTEST.COM for kudu/127.30.86.62@KRBTEST.COM
02:11:54.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.30.86.3@KRBTEST.COM; defaulting to no policy
02:11:54.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:54.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.30.86.3@KRBTEST.COM" created.
02:11:54.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.577365 31456 heartbeater.cc:344] Connected to a master server at 127.30.86.62:41415
02:11:54.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.577836 31456 heartbeater.cc:461] Registering TS with master...
02:11:54.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.578909 31456 heartbeater.cc:507] Master 127.30.86.62:41415 requested a full tablet report, sending...
02:11:54.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.581431 31125 ts_manager.cc:194] Registered new tserver with Master: 43c4a191060f49709b9b75e3937f7192 (127.30.86.2:42405)
02:11:54.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.582878 31125 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.30.86.2@KRBTEST.COM'} at 127.30.86.2:52893
02:11:54.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:54.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:54.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.30.86.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:54.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.30.86.3@KRBTEST.COM; defaulting to no policy
02:11:54.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:54.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.30.86.3@KRBTEST.COM" created.
02:11:54.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:11:54.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:54.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.30.86.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab.
02:11:54.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:54.642992 31064 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:54.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu
02:11:54.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal
02:11:54.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data
02:11:54.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/logs
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data/info.pb
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:54.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.3:0
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.86.3
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.3
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.86.62:41415
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.3
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:11:54.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:11:54.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:54.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:54.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:54.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:54.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:11:54.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:11:54.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:11:54.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:54.973009 31464 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
02:11:54.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:54.973718 31464 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:11:54.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:54.974063 31464 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:11:54.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:54.974828 31464 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:11:55.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:55.008615 31464 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:11:55.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:55.009811 31464 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.30.86.3
02:11:55.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:55.049314 31464 tablet_server_runner.cc:78] Tablet server non-default flags:
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.30.86.20:34145
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/kudu.keytab
02:11:55.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.30.86.3
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.30.86.3:0
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:11:55.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data/info.pb
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.30.86.3
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.30.86.62:41415
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:11:55.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.30.86.3
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/logs
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision a3f35afc2b6dad6aa403ec58378221544a26edd0
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 01:40:41 UTC on e7f111948823
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16850
02:11:55.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:11:55.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:55.051190 31464 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:11:55.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:55.052886 31464 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:11:55.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:55.067395 31470 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
02:11:55.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:55.586656 31456 heartbeater.cc:499] Master 127.30.86.62:41415 was elected leader, sending a full tablet report...
02:11:56.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:55.071597 31471 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
02:11:56.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:56.203329 31464 thread.cc:641] GCE (cloud detector) Time spent creating pthread: real 1.134s	user 0.483s	sys 0.651s
02:11:56.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:56.204277 31464 thread.cc:608] GCE (cloud detector) Time spent starting thread: real 1.135s	user 0.483s	sys 0.651s
02:11:56.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.206363 31464 server_base.cc:1061] running on GCE node
02:11:56.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:56.207741 31476 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
02:11:56.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.209227 31464 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:11:56.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.211934 31464 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:11:56.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.213711 31464 hybrid_clock.cc:648] HybridClock initialized: now 1783476716213645 us; error 52 us; skew 500 ppm
02:11:56.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.214867 31464 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:11:56.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:56 dist-test-slave-r9r8 krb5kdc[31075](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476716, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.86.3@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:11:56.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.223856 31464 init.cc:377] Logged in from keytab as kudu/127.30.86.3@KRBTEST.COM (short username kudu)
02:11:56.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.229598 31464 webserver.cc:534] Webserver started at http://127.30.86.3:45825/ using document root <none> and password file <none>
02:11:56.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.230671 31464 fs_manager.cc:362] Metadata directory not provided
02:11:56.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.230919 31464 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:11:56.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.231546 31464 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:11:56.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.236379 31464 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data/instance:
02:11:56.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59"
02:11:56.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:56 on dist-test-slave-r9r8"
02:11:56.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.237727 31464 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal/instance:
02:11:56.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59"
02:11:56.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:56 on dist-test-slave-r9r8"
02:11:56.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.246263 31464 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.009s	sys 0.001s
02:11:56.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.252535 31482 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:11:56.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.253839 31464 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.002s
02:11:56.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.254196 31464 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data,/tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal
02:11:56.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59"
02:11:56.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 02:11:56 on dist-test-slave-r9r8"
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.254599 31464 fs_report.cc:389] FS layout report
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data/data
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:11:56.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:11:56.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:11:56.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:11:56.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:11:56.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:11:56.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:11:56.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:11:56.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:11:56.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.318506 31464 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:11:56.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.324604 31464 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:11:56.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.325120 31464 kserver.cc:163] Server-wide thread pool size limit: 3276
02:11:56.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.327972 31464 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
02:11:56.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.332255 31464 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
02:11:56.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.332489 31464 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
02:11:56.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.332767 31464 ts_tablet_manager.cc:616] Registered 0 tablets
02:11:56.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.332973 31464 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
02:11:56.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.522243 31464 rpc_server.cc:307] RPC server started. Bound to: 127.30.86.3:45961
02:11:56.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.522336 31594 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.30.86.3:45961 every 8 connection(s)
02:11:56.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.525326 31464 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/data/info.pb
02:11:56.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.527410 31064 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu as pid 31464
02:11:56.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.528250 31064 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal/instance
02:11:56.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:56 dist-test-slave-r9r8 krb5kdc[31075](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783476716, etypes {rep=17 tkt=17 ses=17}, kudu/127.30.86.3@KRBTEST.COM for kudu/127.30.86.62@KRBTEST.COM
02:11:56.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.572556 31595 heartbeater.cc:344] Connected to a master server at 127.30.86.62:41415
02:11:56.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.573020 31595 heartbeater.cc:461] Registering TS with master...
02:11:56.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.574350 31595 heartbeater.cc:507] Master 127.30.86.62:41415 requested a full tablet report, sending...
02:11:56.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.576792 31125 ts_manager.cc:194] Registered new tserver with Master: 11a8fe5ab4904ef5bd4b2ffb44e8cb59 (127.30.86.3:45961)
02:11:56.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.578080 31125 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.30.86.3@KRBTEST.COM'} at 127.30.86.3:42285
02:11:56.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:56.584245 31064 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
02:11:56.597 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
02:11:56.598 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

02:11:56.609 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

02:11:56.620 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.30.86.62"
      port: 41415
    }
    bound_http_address {
      host: "127.30.86.62"
      port: 36951
    }
  }
}

02:11:56.625 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

02:11:56.628 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.30.86.1"
      port: 41023
    }
    bound_http_address {
      host: "127.30.86.1"
      port: 39607
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.30.86.2"
      port: 42405
    }
    bound_http_address {
      host: "127.30.86.2"
      port: 40779
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.30.86.3"
      port: 45961
    }
    bound_http_address {
      host: "127.30.86.3"
      port: 45825
    }
  }
}

02:11:56.629 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
02:11:56.851 [DEBUG - main] (CompositeStrategy.java:59) Unable to load modern Subject methods, relying only on legacy methods
java.lang.NoSuchMethodException: javax.security.auth.Subject.current()
	at java.base/java.lang.Class.getDeclaredMethod(Class.java:2677)
	at org.apache.kudu.client.internals.ModernStrategy.<init>(ModernStrategy.java:51)
	at org.apache.kudu.client.internals.CompositeStrategy.<init>(CompositeStrategy.java:52)
	at org.apache.kudu.client.internals.CompositeStrategy.<clinit>(CompositeStrategy.java:41)
	at org.apache.kudu.client.internals.SecurityManagerCompatibility.get(SecurityManagerCompatibility.java:43)
	at org.apache.kudu.client.SecurityContext.setupSubject(SecurityContext.java:155)
	at org.apache.kudu.client.SecurityContext.<init>(SecurityContext.java:138)
	at org.apache.kudu.client.AsyncKuduClient.<init>(AsyncKuduClient.java:431)
	at org.apache.kudu.client.AsyncKuduClient$AsyncKuduClientBuilder.build(AsyncKuduClient.java:3109)
	at org.apache.kudu.test.KuduTestHarness.before(KuduTestHarness.java:161)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:50)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.doOneAttempt(RetryRule.java:225)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:246)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.runner.JUnitCore.runMain(JUnitCore.java:77)
	at org.junit.runner.JUnitCore.main(JUnitCore.java:36)
02:11:56.878 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/krb5kdc/krb5cc
02:11:56.929 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
02:11:57.053 [DEBUG - main] (Connection.java:748) [peer master-127.30.86.62:41415(127.30.86.62:41415)] connecting to peer
02:11:57.120 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.30.86.62:41415(127.30.86.62:41415)] Successfully connected to peer
02:11:57.216 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.30.86.62
02:11:57.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 02:11:57 dist-test-slave-r9r8 krb5kdc[31075](info): TGS_REQ (1 etypes {17}) 127.0.0.1: ISSUE: authtime 1783476708, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.30.86.62@KRBTEST.COM
02:11:57.497 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xa0ee21f9, L:/127.0.0.1:42288 - R:/127.30.86.62:41415] using SASL/GSSAPI
02:11:57.542 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
02:11:57.544 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=540), Traces: [0ms] refreshing cache from master, [31ms] Sub RPC ConnectToMaster: sending RPC to server master-127.30.86.62:41415, [509ms] Sub RPC ConnectToMaster: received response from server master-127.30.86.62:41415: OK, deferred=null) after lookup
02:11:57.573 [DEBUG - main] (Connection.java:575) [peer master-127.30.86.62:41415(127.30.86.62:41415)] disconnecting while in state READY
02:11:57.580 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.30.86.62:41415(127.30.86.62:41415)] handling channelInactive
02:11:57.581 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.30.86.62:41415(127.30.86.62:41415)] cleaning up while in state READY due to: connection closed
02:11:57.582 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.30.86.62:41415(127.30.86.62:41415) for tablet Kudu Master: connection closed
02:11:57.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.581668 31595 heartbeater.cc:499] Master 127.30.86.62:41415 was elected leader, sending a full tablet report...
02:11:57.586 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.30.86.62:41415(127.30.86.62:41415)] connecting to peer
02:11:57.589 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.30.86.62:41415(127.30.86.62:41415)] Successfully connected to peer
02:11:57.653 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xec551555, L:/127.0.0.1:42292 - R:/127.30.86.62:41415] using TOKEN/null
02:11:57.658 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
02:11:57.658 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=84), Traces: [0ms] sending RPC to server master-127.30.86.62:41415, [8ms] received response from server master-127.30.86.62:41415: Network error: connection closed, [8ms] delaying RPC due to: Network error: connection closed, [11ms] refreshing cache from master, [11ms] Sub RPC ConnectToMaster: sending RPC to server master-127.30.86.62:41415, [83ms] Sub RPC ConnectToMaster: received response from server master-127.30.86.62:41415: OK, deferred=Deferred@1273770728(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
02:11:57.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.722944 31125 catalog_manager.cc:2282] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:42292:
02:11:57.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
02:11:57.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
02:11:57.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:11:57.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
02:11:57.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
02:11:57.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:11:57.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
02:11:57.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
02:11:57.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
02:11:57.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
02:11:57.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
02:11:57.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
02:11:57.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
02:11:57.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:11:57.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
02:11:57.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:57.743614 31125 catalog_manager.cc:7076] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table TestAuthnTokenReacquireOpen-table in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
02:11:57.819 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 359bc40b9d5d49f6bb6dd183d97fe197
02:11:57.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.829041 31391 tablet_service.cc:1511] Processing CreateTablet for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=359bc40b9d5d49f6bb6dd183d97fe197]), partition=RANGE (key) PARTITION UNBOUNDED
02:11:57.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.832536 31530 tablet_service.cc:1511] Processing CreateTablet for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=359bc40b9d5d49f6bb6dd183d97fe197]), partition=RANGE (key) PARTITION UNBOUNDED
02:11:57.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.834625 31530 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8. 1 dirs total, 0 dirs full, 0 dirs failed
02:11:57.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.835798 31253 tablet_service.cc:1511] Processing CreateTablet for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=359bc40b9d5d49f6bb6dd183d97fe197]), partition=RANGE (key) PARTITION UNBOUNDED
02:11:57.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.841166 31253 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8. 1 dirs total, 0 dirs full, 0 dirs failed
02:11:57.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.845218 31391 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8. 1 dirs total, 0 dirs full, 0 dirs failed
02:11:57.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.872506 31615 tablet_bootstrap.cc:492] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Bootstrap starting.
02:11:57.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.882647 31616 tablet_bootstrap.cc:492] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Bootstrap starting.
02:11:57.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.888154 31615 tablet_bootstrap.cc:654] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Neither blocks nor log segments found. Creating new log.
02:11:57.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.891258 31615 log.cc:826] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Log is configured to *not* fsync() on all Append() calls
02:11:57.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.896144 31616 tablet_bootstrap.cc:654] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Neither blocks nor log segments found. Creating new log.
02:11:57.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.899415 31616 log.cc:826] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Log is configured to *not* fsync() on all Append() calls
02:11:57.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.901724 31617 tablet_bootstrap.cc:492] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: Bootstrap starting.
02:11:57.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.906368 31615 tablet_bootstrap.cc:492] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: No bootstrap required, opened a new log
02:11:57.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.907017 31615 ts_tablet_manager.cc:1403] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Time spent bootstrapping tablet: real 0.035s	user 0.013s	sys 0.013s
02:11:57.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.910218 31617 tablet_bootstrap.cc:654] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: Neither blocks nor log segments found. Creating new log.
02:11:57.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.912711 31617 log.cc:826] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: Log is configured to *not* fsync() on all Append() calls
02:11:57.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.924207 31616 tablet_bootstrap.cc:492] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: No bootstrap required, opened a new log
02:11:57.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.924989 31617 tablet_bootstrap.cc:492] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: No bootstrap required, opened a new log
02:11:57.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.925316 31616 ts_tablet_manager.cc:1403] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Time spent bootstrapping tablet: real 0.043s	user 0.014s	sys 0.007s
02:11:57.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.925911 31617 ts_tablet_manager.cc:1403] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: Time spent bootstrapping tablet: real 0.026s	user 0.010s	sys 0.012s
02:11:57.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.938367 31615 raft_consensus.cc:359] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:57.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.939961 31615 raft_consensus.cc:385] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:11:57.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.940665 31615 raft_consensus.cc:740] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 11a8fe5ab4904ef5bd4b2ffb44e8cb59, State: Initialized, Role: FOLLOWER
02:11:57.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.942893 31615 consensus_queue.cc:260] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [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: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:57.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.953397 31616 raft_consensus.cc:359] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.956002 31616 raft_consensus.cc:385] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:11:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.956790 31616 raft_consensus.cc:740] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 43c4a191060f49709b9b75e3937f7192, State: Initialized, Role: FOLLOWER
02:11:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.956961 31615 ts_tablet_manager.cc:1434] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Time spent starting tablet: real 0.050s	user 0.039s	sys 0.003s
02:11:57.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.960871 31616 consensus_queue.cc:260] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [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: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:57.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.964267 31617 raft_consensus.cc:359] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:57.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.965736 31617 raft_consensus.cc:385] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:11:57.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.966392 31617 raft_consensus.cc:740] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 60bfd4e17c40476d94b561e41458ce9b, State: Initialized, Role: FOLLOWER
02:11:57.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.967926 31617 consensus_queue.cc:260] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [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: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:57.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.982129 31616 ts_tablet_manager.cc:1434] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Time spent starting tablet: real 0.056s	user 0.032s	sys 0.016s
02:11:57.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.984547 31617 ts_tablet_manager.cc:1434] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: Time spent starting tablet: real 0.058s	user 0.036s	sys 0.007s
02:11:57.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.989192 31623 raft_consensus.cc:493] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
02:11:57.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.990756 31623 raft_consensus.cc:515] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:57.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:57.994696 31623 leader_election.cc:290] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 11a8fe5ab4904ef5bd4b2ffb44e8cb59 (127.30.86.3:45961), 43c4a191060f49709b9b75e3937f7192 (127.30.86.2:42405)
02:11:58.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.008180 31550 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "8ec29a597e2d4991a8f716fb9f0b4ba8" candidate_uuid: "60bfd4e17c40476d94b561e41458ce9b" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" is_pre_election: true
02:11:58.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.009135 31550 raft_consensus.cc:2468] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 60bfd4e17c40476d94b561e41458ce9b in term 0.
02:11:58.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.009570 31411 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "8ec29a597e2d4991a8f716fb9f0b4ba8" candidate_uuid: "60bfd4e17c40476d94b561e41458ce9b" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "43c4a191060f49709b9b75e3937f7192" is_pre_election: true
02:11:58.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.010488 31206 leader_election.cc:304] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [CANDIDATE]: Term 1 pre-election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 11a8fe5ab4904ef5bd4b2ffb44e8cb59, 60bfd4e17c40476d94b561e41458ce9b; no voters: 
02:11:58.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.010468 31411 raft_consensus.cc:2468] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 60bfd4e17c40476d94b561e41458ce9b in term 0.
02:11:58.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.011168 31623 raft_consensus.cc:2804] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Leader pre-election won for term 1
02:11:58.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.011507 31623 raft_consensus.cc:493] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
02:11:58.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.011818 31623 raft_consensus.cc:3060] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 0 FOLLOWER]: Advancing to term 1
02:11:58.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.016544 31623 raft_consensus.cc:515] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:58.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.019479 31411 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "8ec29a597e2d4991a8f716fb9f0b4ba8" candidate_uuid: "60bfd4e17c40476d94b561e41458ce9b" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "43c4a191060f49709b9b75e3937f7192"
02:11:58.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.019979 31411 raft_consensus.cc:3060] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 0 FOLLOWER]: Advancing to term 1
02:11:58.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.026805 31550 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "8ec29a597e2d4991a8f716fb9f0b4ba8" candidate_uuid: "60bfd4e17c40476d94b561e41458ce9b" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59"
02:11:58.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.028928 31550 raft_consensus.cc:3060] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 0 FOLLOWER]: Advancing to term 1
02:11:58.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:58.032437 31596 tablet.cc:2406] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Can't schedule compaction. Clean time has not been advanced past its initial value.
02:11:58.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.036351 31623 leader_election.cc:290] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [CANDIDATE]: Term 1 election: Requested vote from peers 11a8fe5ab4904ef5bd4b2ffb44e8cb59 (127.30.86.3:45961), 43c4a191060f49709b9b75e3937f7192 (127.30.86.2:42405)
02:11:58.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.038400 31411 raft_consensus.cc:2468] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 60bfd4e17c40476d94b561e41458ce9b in term 1.
02:11:58.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.041574 31209 leader_election.cc:304] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 43c4a191060f49709b9b75e3937f7192, 60bfd4e17c40476d94b561e41458ce9b; no voters: 
02:11:58.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.043774 31623 raft_consensus.cc:2804] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 1 FOLLOWER]: Leader election won for term 1
02:11:58.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:58.047801 31457 tablet.cc:2406] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Can't schedule compaction. Clean time has not been advanced past its initial value.
02:11:58.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.049636 31550 raft_consensus.cc:2468] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 60bfd4e17c40476d94b561e41458ce9b in term 1.
02:11:58.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.052618 31623 raft_consensus.cc:697] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 1 LEADER]: Becoming Leader. State: Replica: 60bfd4e17c40476d94b561e41458ce9b, State: Running, Role: LEADER
02:11:58.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.054212 31623 consensus_queue.cc:237] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [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: 2, State: 0, Mode: LEADER, active raft config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } }
02:11:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.070384 31125 catalog_manager.cc:5696] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b reported cstate change: term changed from 0 to 1, leader changed from <none> to 60bfd4e17c40476d94b561e41458ce9b (127.30.86.1). New cstate: current_term: 1 leader_uuid: "60bfd4e17c40476d94b561e41458ce9b" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "60bfd4e17c40476d94b561e41458ce9b" member_type: VOTER last_known_addr { host: "127.30.86.1" port: 41023 } health_report { overall_health: HEALTHY } } }
02:11:58.116 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 359bc40b9d5d49f6bb6dd183d97fe197
02:11:58.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:58.135339 31319 tablet.cc:2406] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: Can't schedule compaction. Clean time has not been advanced past its initial value.
02:11:58.505 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.504845 31623 consensus_queue.cc:1048] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [LEADER]: Connected to new peer: Peer: permanent_uuid: "43c4a191060f49709b9b75e3937f7192" member_type: VOTER last_known_addr { host: "127.30.86.2" port: 42405 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
02:11:58.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:58.524041 31623 consensus_queue.cc:1048] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [LEADER]: Connected to new peer: Peer: permanent_uuid: "11a8fe5ab4904ef5bd4b2ffb44e8cb59" member_type: VOTER last_known_addr { host: "127.30.86.3" port: 45961 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.001s
02:11:59.122 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 359bc40b9d5d49f6bb6dd183d97fe197
02:11:59.147 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
02:11:59.153 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=8ec29a597e2d4991a8f716fb9f0b4ba8@[11a8fe5ab4904ef5bd4b2ffb44e8cb59(127.30.86.3:45961),43c4a191060f49709b9b75e3937f7192(127.30.86.2:42405),60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)[L]]}]
02:11:59.164 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC ScanRequest(scannerId=null, state=OPENING, attempt=1, KuduRpc(method=Scan, tablet=null, attempt=1, TimeoutTracker(timeout=60000, elapsed=17), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.30.86.62:41415, [9ms] Sub RPC GetTableLocations: received response from server master-127.30.86.62:41415: OK, deferred=null)) after lookup
02:11:59.175 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] connecting to peer
02:11:59.177 [DEBUG - kudu-nio-2] (Connection.java:757) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] Successfully connected to peer
02:11:59.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:59.229691 31638 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:54792: token expired
02:11:59.235 [DEBUG - kudu-nio-2] (Negotiator.java:352) peer /127.30.86.1:41023 sent connection negotiation error: Not authorized: token expired
02:11:59.237 [DEBUG - kudu-nio-2] (Connection.java:239) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] handling channelInactive
02:11:59.238 [DEBUG - kudu-nio-2] (Connection.java:709) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
02:11:59.239 [DEBUG - kudu-nio-2] (Connection.java:748) [peer master-127.30.86.62:41415(127.30.86.62:41415)] connecting to peer
02:11:59.242 [DEBUG - kudu-nio-3] (Connection.java:757) [peer master-127.30.86.62:41415(127.30.86.62:41415)] Successfully connected to peer
02:11:59.250 [DEBUG - kudu-nio-3] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.30.86.62
02:11:59.296 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x6c9737e2, L:/127.0.0.1:55798 - R:/127.30.86.62:41415] using SASL/GSSAPI
02:11:59.300 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
02:11:59.301 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] connecting to peer
02:11:59.303 [DEBUG - kudu-nio-4] (Connection.java:757) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] Successfully connected to peer
02:11:59.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:59.330049 31638 fault_injection.cc:43] FAULT INJECTION ENABLED!
02:11:59.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:59.330338 31638 fault_injection.cc:44] THIS SERVER MAY CRASH!
02:11:59.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:59.331269 31638 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:54794: token signing key expired
02:11:59.332 [DEBUG - kudu-nio-4] (Negotiator.java:352) peer /127.30.86.1:41023 sent connection negotiation error: Not authorized: token signing key expired
02:11:59.334 [DEBUG - kudu-nio-4] (Connection.java:239) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] handling channelInactive
02:11:59.334 [DEBUG - kudu-nio-4] (Connection.java:709) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
02:11:59.338 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
02:11:59.339 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] connecting to peer
02:11:59.341 [DEBUG - kudu-nio-5] (Connection.java:757) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] Successfully connected to peer
02:11:59.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:59.379284 31638 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:54804: invalid token signature
02:11:59.380 [DEBUG - kudu-nio-5] (Negotiator.java:352) peer /127.30.86.1:41023 sent connection negotiation error: Not authorized: invalid token signature
02:11:59.382 [DEBUG - kudu-nio-5] (Connection.java:239) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] handling channelInactive
02:11:59.383 [DEBUG - kudu-nio-5] (Connection.java:709) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
02:11:59.387 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
02:11:59.388 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] connecting to peer
02:11:59.390 [DEBUG - kudu-nio-6] (Connection.java:757) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] Successfully connected to peer
02:11:59.425 [DEBUG - kudu-nio-6] (Negotiator.java:919) Authenticated connection [id: 0xeeee3ffc, L:/127.0.0.1:54814 - R:/127.30.86.1:41023] using TOKEN/null
02:11:59.461 [DEBUG - kudu-nio-6] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=8ec29a597e2d4991a8f716fb9f0b4ba8, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
02:11:59.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.475370 31124 catalog_manager.cc:2532] Servicing SoftDeleteTable request from {username='test-admin'} at 127.0.0.1:42292:
02:11:59.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
02:11:59.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.476105 31124 catalog_manager.cc:2780] Servicing DeleteTable request from {username='test-admin'} at 127.0.0.1:42292:
02:11:59.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
02:11:59.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.491058 31124 catalog_manager.cc:5983] T 00000000000000000000000000000000 P 166034d2dbbf4edeb36de2fef08b0d0f: Sending DeleteTablet for 3 replicas of tablet 8ec29a597e2d4991a8f716fb9f0b4ba8
02:11:59.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.493471 31530 tablet_service.cc:1558] Processing DeleteTablet for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 02:11:59 UTC) from {username='kudu', principal='kudu/127.30.86.62@KRBTEST.COM'} at 127.0.0.1:33520
02:11:59.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.493927 31391 tablet_service.cc:1558] Processing DeleteTablet for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 02:11:59 UTC) from {username='kudu', principal='kudu/127.30.86.62@KRBTEST.COM'} at 127.0.0.1:44518
02:11:59.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.495054 31253 tablet_service.cc:1558] Processing DeleteTablet for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 02:11:59 UTC) from {username='kudu', principal='kudu/127.30.86.62@KRBTEST.COM'} at 127.0.0.1:53364
02:11:59.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.496999 31650 tablet_replica.cc:333] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: stopping tablet replica
02:11:59.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.503397 31650 raft_consensus.cc:2243] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 1 FOLLOWER]: Raft consensus shutting down.
02:11:59.510 [DEBUG - main] (Connection.java:575) [peer master-127.30.86.62:41415(127.30.86.62:41415)] disconnecting while in state READY
02:11:59.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.510602 31650 raft_consensus.cc:2272] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59 [term 1 FOLLOWER]: Raft consensus is shut down!
02:11:59.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.510708 31651 tablet_replica.cc:333] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: stopping tablet replica
02:11:59.511 [DEBUG - main] (Connection.java:575) [peer master-127.30.86.62:41415(127.30.86.62:41415)] disconnecting while in state READY
02:11:59.511 [DEBUG - main] (Connection.java:575) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] disconnecting while in state READY
02:11:59.512 [DEBUG - kudu-nio-3] (Connection.java:239) [peer master-127.30.86.62:41415(127.30.86.62:41415)] handling channelInactive
02:11:59.512 [DEBUG - kudu-nio-3] (Connection.java:709) [peer master-127.30.86.62:41415(127.30.86.62:41415)] cleaning up while in state READY due to: connection closed
02:11:59.513 [DEBUG - kudu-nio-6] (Connection.java:239) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] handling channelInactive
02:11:59.514 [DEBUG - kudu-nio-6] (Connection.java:709) [peer 60bfd4e17c40476d94b561e41458ce9b(127.30.86.1:41023)] cleaning up while in state READY due to: connection closed
02:11:59.515 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2704) Releasing all remaining resources
02:11:59.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.516146 31652 tablet_replica.cc:333] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: stopping tablet replica
02:11:59.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.516428 31650 ts_tablet_manager.cc:1916] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Deleting tablet data with delete state TABLET_DATA_DELETED
02:11:59.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.518129 31652 raft_consensus.cc:2243] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 1 LEADER]: Raft consensus shutting down.
02:11:59.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.522454 31651 raft_consensus.cc:2243] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 1 FOLLOWER]: Raft consensus shutting down.
02:11:59.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.523389 31651 raft_consensus.cc:2272] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192 [term 1 FOLLOWER]: Raft consensus is shut down!
02:11:59.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.526222 31652 raft_consensus.cc:2272] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b [term 1 FOLLOWER]: Raft consensus is shut down!
02:11:59.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.527606 31651 ts_tablet_manager.cc:1916] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Deleting tablet data with delete state TABLET_DATA_DELETED
02:11:59.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.528877 31652 ts_tablet_manager.cc:1916] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 60bfd4e17c40476d94b561e41458ce9b: Deleting tablet data with delete state TABLET_DATA_DELETED
02:11:59.543 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.30.86.62:41415(127.30.86.62:41415)] handling channelInactive
02:11:59.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.543637 31064 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu with pid 31188
02:11:59.544 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.30.86.62:41415(127.30.86.62:41415)] cleaning up while in state READY due to: connection closed
02:11:59.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.549064 31651 ts_tablet_manager.cc:1929] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
02:11:59.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.549860 31651 log.cc:1199] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Deleting WAL directory at /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-1/wal/wals/8ec29a597e2d4991a8f716fb9f0b4ba8
02:11:59.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.550921 31651 ts_tablet_manager.cc:1950] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 43c4a191060f49709b9b75e3937f7192: Deleting consensus metadata
02:11:59.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.554407 31650 ts_tablet_manager.cc:1929] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
02:11:59.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.554798 31112 catalog_manager.cc:5027] TS 43c4a191060f49709b9b75e3937f7192 (127.30.86.2:42405): tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 (table TestAuthnTokenReacquireOpen-table [id=359bc40b9d5d49f6bb6dd183d97fe197]) successfully deleted
02:11:59.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.555122 31650 log.cc:1199] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Deleting WAL directory at /tmp/dist-test-taskljtXwN/test-tmp/mini-kudu-cluster7677114162765774518/ts-2/wal/wals/8ec29a597e2d4991a8f716fb9f0b4ba8
02:11:59.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.555981 31650 ts_tablet_manager.cc:1950] T 8ec29a597e2d4991a8f716fb9f0b4ba8 P 11a8fe5ab4904ef5bd4b2ffb44e8cb59: Deleting consensus metadata
02:11:59.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.559541 31109 catalog_manager.cc:5027] TS 11a8fe5ab4904ef5bd4b2ffb44e8cb59 (127.30.86.3:45961): tablet 8ec29a597e2d4991a8f716fb9f0b4ba8 (table TestAuthnTokenReacquireOpen-table [id=359bc40b9d5d49f6bb6dd183d97fe197]) successfully deleted
02:11:59.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:59.576721 31109 proxy.cc:239] Call had error, refreshing address and retrying: Network error: recv got EOF from 127.30.86.1:41023 (error 108)
02:11:59.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.578289 31064 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu with pid 31326
02:11:59.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 02:11:59.583132 31109 catalog_manager.cc:4754] TS 60bfd4e17c40476d94b561e41458ce9b (127.30.86.1:41023): DeleteTablet:TABLET_DATA_DELETED RPC failed for tablet 8ec29a597e2d4991a8f716fb9f0b4ba8: Network error: Client connection negotiation failed: client connection to 127.30.86.1:41023: connect: Connection refused (error 111)
02:11:59.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.600858 31064 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu with pid 31464
02:11:59.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 02:11:59.623445 31064 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskljtXwN/build/tsan/bin/kudu with pid 31091
02:12:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T02:12:00Z chronyd exiting

Time: 16.137

OK (1 test)

