JUnit version 4.13.2
.10:32:30.164 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1786444350154
10:32:30.193 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
10:32:30.230 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
10:32:30.239 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskCtpRxu/test-tmp
10:32:30.265 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/asan/bin
10:32:30.268 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskCtpRxu/java/build/dist-test/../../../build/asan/bin/kudu, test, mini_cluster, --serialization=pb]
10:32:30.480 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475"
  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 {
  }
}

10:32:30.687 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
10:32:30.688 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

10:32:30.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:30.692584 17967 mini_oidc.cc:150] Starting JWKS server
10:32:30.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:30.695721 17967 webserver.cc:533] Webserver started at http://127.0.0.1:39953/ using document root <none> and password file <none>
10:32:30.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:30.696034 17967 mini_oidc.cc:186] Starting OIDC Discovery server
10:32:30.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:30.697294 17967 webserver.cc:533] Webserver started at http://127.0.0.1:45325/ using document root <none> and password file <none>
10:32:30.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
10:32:30.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/principal' for realm 'KRBTEST.COM',
10:32:30.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
10:32:30.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:30 dist-test-slave-n326 krb5kdc[17983](info): setting up network...
10:32:30.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
10:32:30.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:30 dist-test-slave-n326 krb5kdc[17983](info): set up 2 sockets
10:32:30.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:30 dist-test-slave-n326 krb5kdc[17983](info): commencing operation
10:32:30.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
10:32:33.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.066557 17967 mini_kdc.cc:121] Time spent starting KDC: real 2.360s	user 0.006s	sys 0.013s
10:32:33.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
10:32:33.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
10:32:33.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
10:32:33.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
10:32:33.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
10:32:33.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
10:32:33.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
10:32:33.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
10:32:33.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
10:32:33.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
10:32:33.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-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/test-user.keytab.
10:32:33.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-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/test-user.keytab.
10:32:33.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:33 dist-test-slave-n326 krb5kdc[17983](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444353, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
10:32:33.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
10:32:33.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T10:32:33Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
10:32:33.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T10:32:33Z Disabled control of system clock
10:32:33.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.139.254@KRBTEST.COM; defaulting to no policy
10:32:33.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:33.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.139.254@KRBTEST.COM" created.
10:32:33.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:33.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.254 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:33.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.254 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:33.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.17.139.254@KRBTEST.COM; defaulting to no policy
10:32:33.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:33.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.17.139.254@KRBTEST.COM" created.
10:32:33.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:33.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.254 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:33.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.254 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:33.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.301736 17967 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:33.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:33.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/wal
10:32:33.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data
10:32:33.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
10:32:33.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
10:32:33.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
10:32:33.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:33.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:33.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:33.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/logs
10:32:33.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data/info.pb
10:32:33.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:33.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
10:32:33.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
10:32:33.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
10:32:33.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
10:32:33.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:33.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:33.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:33.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
10:32:33.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
10:32:33.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
10:32:33.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
10:32:33.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.254:41819
10:32:33.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.254
10:32:33.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:33.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:33.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.254
10:32:33.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:33.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
10:32:33.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
10:32:33.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
10:32:33.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:33.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:33.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:33.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:33.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
10:32:33.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.17.139.254:41819
10:32:33.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
10:32:33.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:39953/jwks.json
10:32:33.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
10:32:33.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
10:32:33.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
10:32:33.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.639012 18013 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
10:32:33.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.639540 18013 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
10:32:33.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.639683 18013 flags.cc:432] Enabled unsafe flag: --never_fsync=true
10:32:33.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.648451 18013 flags.cc:432] Enabled experimental flag: --authn_token_validity_seconds=1
10:32:33.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.648578 18013 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
10:32:33.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.648682 18013 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
10:32:33.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.648743 18013 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
10:32:33.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.648829 18013 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
10:32:33.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.648890 18013 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
10:32:33.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.648957 18013 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:39953/jwks.json
10:32:33.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.660872 18013 master_runner.cc:387] Master server non-default flags:
10:32:33.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/logs
10:32:33.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:33.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
10:32:33.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:33.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:33.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:33.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:33.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data
10:32:33.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/wal
10:32:33.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
10:32:33.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
10:32:33.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.17.139.254:41819
10:32:33.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:33.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.254
10:32:33.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:33.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:33.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
10:32:33.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.254:41819
10:32:33.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
10:32:33.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
10:32:33.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
10:32:33.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:39953/jwks.json
10:32:33.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:33.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
10:32:33.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
10:32:33.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
10:32:33.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:33.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:33.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data/info.pb
10:32:33.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.254
10:32:33.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:33.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
10:32:33.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:33.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
10:32:33.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
10:32:33.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:33.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
10:32:33.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
10:32:33.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
10:32:33.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 14880490066cc6e355a232e5a6411307cd134162
10:32:33.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
10:32:33.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 10:24:06 UTC on bdcb31816ec0
10:32:33.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 17617
10:32:33.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
10:32:33.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.663476 18013 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
10:32:33.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.665689 18013 file_cache.cc:504] Constructed file cache file cache with capacity 419430
10:32:33.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.677749 18027 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
10:32:33.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.677708 18025 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
10:32:33.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:33.678110 18031 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
10:32:33.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.678717 18013 server_base.cc:1061] running on GCE node
10:32:33.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.679713 18013 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
10:32:33.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.681396 18013 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
10:32:33.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.682934 18013 hybrid_clock.cc:648] HybridClock initialized: now 1786444353682696 us; error 97 us; skew 500 ppm
10:32:33.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.683558 18013 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
10:32:33.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:33 dist-test-slave-n326 krb5kdc[17983](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444353, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.139.254@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
10:32:33.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.687389 18013 init.cc:377] Logged in from keytab as kudu/127.17.139.254@KRBTEST.COM (short username kudu)
10:32:33.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.689181 18013 webserver.cc:533] Webserver started at http://127.17.139.254:42295/ using document root <none> and password file <none>
10:32:33.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.690164 18013 fs_manager.cc:362] Metadata directory not provided
10:32:33.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.690325 18013 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
10:32:33.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.690874 18013 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
10:32:33.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.693713 18013 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data/instance:
10:32:33.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "506b17e222db4681b1c39f45a2d69200"
10:32:33.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:33 on dist-test-slave-n326"
10:32:33.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.694945 18013 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/wal/instance:
10:32:33.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "506b17e222db4681b1c39f45a2d69200"
10:32:33.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:33 on dist-test-slave-n326"
10:32:33.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.701172 18013 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.004s	sys 0.000s
10:32:33.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.704967 18043 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
10:32:33.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.706419 18013 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.000s	sys 0.001s
10:32:33.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.706616 18013 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data,/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/wal
10:32:33.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "506b17e222db4681b1c39f45a2d69200"
10:32:33.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:33 on dist-test-slave-n326"
10:32:33.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.706875 18013 fs_report.cc:389] FS layout report
10:32:33.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
10:32:33.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/wal
10:32:33.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/wal
10:32:33.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data/data
10:32:33.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
10:32:33.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
10:32:33.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
10:32:33.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
10:32:33.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
10:32:33.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
10:32:33.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
10:32:33.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
10:32:33.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
10:32:33.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
10:32:33.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
10:32:33.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.745201 18013 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
10:32:33.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.750590 18013 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
10:32:33.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.751009 18013 kserver.cc:163] Server-wide thread pool size limit: 3276
10:32:33.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.774765 18013 rpc_server.cc:307] RPC server started. Bound to: 127.17.139.254:41819
10:32:33.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.774816 18125 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.17.139.254:41819 every 8 connection(s)
10:32:33.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.777228 18013 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/data/info.pb
10:32:33.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.781876 18126 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
10:32:33.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.786139 17967 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu as pid 18013
10:32:33.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.786449 17967 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/master-0/wal/instance
10:32:33.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.792028 18126 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200: Bootstrap starting.
10:32:33.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.797479 18126 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200: Neither blocks nor log segments found. Creating new log.
10:32:33.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.799999 18126 log.cc:826] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200: Log is configured to *not* fsync() on all Append() calls
10:32:33.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.811314 18126 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200: No bootstrap required, opened a new log
10:32:33.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.817858 18126 raft_consensus.cc:359] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "506b17e222db4681b1c39f45a2d69200" member_type: VOTER last_known_addr { host: "127.17.139.254" port: 41819 } }
10:32:33.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.818216 18126 raft_consensus.cc:385] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
10:32:33.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.818296 18126 raft_consensus.cc:740] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 506b17e222db4681b1c39f45a2d69200, State: Initialized, Role: FOLLOWER
10:32:33.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.819047 18126 consensus_queue.cc:260] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [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: "506b17e222db4681b1c39f45a2d69200" member_type: VOTER last_known_addr { host: "127.17.139.254" port: 41819 } }
10:32:33.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.819319 18126 raft_consensus.cc:399] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
10:32:33.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.819471 18126 raft_consensus.cc:493] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
10:32:33.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.819664 18126 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 0 FOLLOWER]: Advancing to term 1
10:32:33.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.823043 18126 raft_consensus.cc:515] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "506b17e222db4681b1c39f45a2d69200" member_type: VOTER last_known_addr { host: "127.17.139.254" port: 41819 } }
10:32:33.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.824052 18126 leader_election.cc:304] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [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: 506b17e222db4681b1c39f45a2d69200; no voters: 
10:32:33.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.825143 18126 leader_election.cc:290] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [CANDIDATE]: Term 1 election: Requested vote from peers 
10:32:33.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.825299 18135 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 1 FOLLOWER]: Leader election won for term 1
10:32:33.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:33 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444353, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.17.139.254@KRBTEST.COM
10:32:33.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.829519 18126 sys_catalog.cc:565] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [sys.catalog]: configured and running, proceeding with master startup.
10:32:33.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.830193 18135 raft_consensus.cc:697] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [term 1 LEADER]: Becoming Leader. State: Replica: 506b17e222db4681b1c39f45a2d69200, State: Running, Role: LEADER
10:32:33.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.831135 18135 consensus_queue.cc:237] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [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: "506b17e222db4681b1c39f45a2d69200" member_type: VOTER last_known_addr { host: "127.17.139.254" port: 41819 } }
10:32:33.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.836084 18136 sys_catalog.cc:455] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "506b17e222db4681b1c39f45a2d69200" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "506b17e222db4681b1c39f45a2d69200" member_type: VOTER last_known_addr { host: "127.17.139.254" port: 41819 } } }
10:32:33.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.837033 18136 sys_catalog.cc:458] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [sys.catalog]: This master's current role is: LEADER
10:32:33.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.839516 18138 sys_catalog.cc:455] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 506b17e222db4681b1c39f45a2d69200. Latest consensus state: current_term: 1 leader_uuid: "506b17e222db4681b1c39f45a2d69200" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "506b17e222db4681b1c39f45a2d69200" member_type: VOTER last_known_addr { host: "127.17.139.254" port: 41819 } } }
10:32:33.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.840843 18138 sys_catalog.cc:458] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200 [sys.catalog]: This master's current role is: LEADER
10:32:33.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.847481 18161 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
10:32:33.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.853432 18161 catalog_manager.cc:1520] Initializing Kudu cluster ID...
10:32:33.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.865264 18161 catalog_manager.cc:1383] Generated new cluster ID: c272a1f74a7940d4887793b175bb5b98
10:32:33.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.865388 18161 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
10:32:33.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.894114 18161 catalog_manager.cc:1406] Generated new certificate authority record
10:32:33.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.896075 18161 catalog_manager.cc:1540] Loading token signing keys...
10:32:33.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.908917 18161 catalog_manager.cc:6092] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200: Generated new TSK 0
10:32:33.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:33.910177 18161 catalog_manager.cc:1550] Initializing in-progress tserver states...
10:32:33.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.139.193@KRBTEST.COM; defaulting to no policy
10:32:33.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:33.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.139.193@KRBTEST.COM" created.
10:32:33.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:33.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.193 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:33.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.193 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:33.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.17.139.193@KRBTEST.COM; defaulting to no policy
10:32:33.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:33.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.17.139.193@KRBTEST.COM" created.
10:32:34.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:34.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.193 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:34.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.193 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:34.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.024605 17967 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:34.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:34.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal
10:32:34.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data
10:32:34.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
10:32:34.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
10:32:34.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
10:32:34.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:34.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:34.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:34.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/logs
10:32:34.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data/info.pb
10:32:34.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:34.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
10:32:34.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
10:32:34.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
10:32:34.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
10:32:34.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:34.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:34.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:34.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
10:32:34.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
10:32:34.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.193:0
10:32:34.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.139.193
10:32:34.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.193
10:32:34.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:34.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.139.254:41819
10:32:34.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:34.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.193
10:32:34.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:34.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
10:32:34.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
10:32:34.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
10:32:34.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:34.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:34.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:34.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:34.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
10:32:34.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
10:32:34.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
10:32:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.376071 18182 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
10:32:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.376384 18182 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
10:32:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.376431 18182 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
10:32:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.376529 18182 flags.cc:432] Enabled unsafe flag: --never_fsync=true
10:32:34.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.384922 18182 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
10:32:34.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.385109 18182 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.17.139.193
10:32:34.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.395515 18182 tablet_server_runner.cc:78] Tablet server non-default flags:
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/logs
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data
10:32:34.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.193
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.193:0
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:34.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
10:32:34.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
10:32:34.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
10:32:34.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:34.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:34.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data/info.pb
10:32:34.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.193
10:32:34.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:34.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.139.254:41819
10:32:34.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
10:32:34.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:34.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
10:32:34.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
10:32:34.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:34.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.139.193
10:32:34.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
10:32:34.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
10:32:34.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
10:32:34.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 14880490066cc6e355a232e5a6411307cd134162
10:32:34.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
10:32:34.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 10:24:06 UTC on bdcb31816ec0
10:32:34.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 17617
10:32:34.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
10:32:34.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.397359 18182 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
10:32:34.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.399302 18182 file_cache.cc:504] Constructed file cache file cache with capacity 419430
10:32:34.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.411520 18191 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
10:32:34.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.411530 18190 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
10:32:34.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.413937 18193 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
10:32:34.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.414108 18182 server_base.cc:1061] running on GCE node
10:32:34.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.415272 18182 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
10:32:34.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.416523 18182 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
10:32:34.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.417811 18182 hybrid_clock.cc:648] HybridClock initialized: now 1786444354417742 us; error 44 us; skew 500 ppm
10:32:34.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.418232 18182 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
10:32:34.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:34 dist-test-slave-n326 krb5kdc[17983](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444354, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.139.193@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
10:32:34.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.421875 18182 init.cc:377] Logged in from keytab as kudu/127.17.139.193@KRBTEST.COM (short username kudu)
10:32:34.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.423472 18182 webserver.cc:533] Webserver started at http://127.17.139.193:36959/ using document root <none> and password file <none>
10:32:34.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.424285 18182 fs_manager.cc:362] Metadata directory not provided
10:32:34.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.424446 18182 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
10:32:34.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.424906 18182 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
10:32:34.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.427533 18182 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data/instance:
10:32:34.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "52a9ee62f26e406798dbd7d654ce55d2"
10:32:34.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:34 on dist-test-slave-n326"
10:32:34.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.428529 18182 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal/instance:
10:32:34.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "52a9ee62f26e406798dbd7d654ce55d2"
10:32:34.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:34 on dist-test-slave-n326"
10:32:34.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.434140 18182 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.005s	sys 0.001s
10:32:34.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.437716 18204 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.439021 18182 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.000s
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.439219 18182 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data,/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "52a9ee62f26e406798dbd7d654ce55d2"
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:34 on dist-test-slave-n326"
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.439395 18182 fs_report.cc:389] FS layout report
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data/data
10:32:34.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
10:32:34.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
10:32:34.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.469110 18182 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
10:32:34.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.473416 18182 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
10:32:34.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.473649 18182 kserver.cc:163] Server-wide thread pool size limit: 3276
10:32:34.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.474749 18182 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
10:32:34.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.476850 18182 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
10:32:34.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.477243 18182 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.001s	sys 0.000s
10:32:34.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.477385 18182 ts_tablet_manager.cc:616] Registered 0 tablets
10:32:34.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.477463 18182 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
10:32:34.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.515311 18182 rpc_server.cc:307] RPC server started. Bound to: 127.17.139.193:36971
10:32:34.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.515411 18405 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.17.139.193:36971 every 8 connection(s)
10:32:34.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.516685 18182 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/data/info.pb
10:32:34.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.522943 17967 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu as pid 18182
10:32:34.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.523209 17967 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal/instance
10:32:34.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:34 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444354, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.139.193@KRBTEST.COM for kudu/127.17.139.254@KRBTEST.COM
10:32:34.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.139.194@KRBTEST.COM; defaulting to no policy
10:32:34.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:34.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.139.194@KRBTEST.COM" created.
10:32:34.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.555557 18406 heartbeater.cc:344] Connected to a master server at 127.17.139.254:41819
10:32:34.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.556010 18406 heartbeater.cc:461] Registering TS with master...
10:32:34.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.556993 18406 heartbeater.cc:507] Master 127.17.139.254:41819 requested a full tablet report, sending...
10:32:34.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.559758 18072 ts_manager.cc:194] Registered new tserver with Master: 52a9ee62f26e406798dbd7d654ce55d2 (127.17.139.193:36971)
10:32:34.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.562121 18072 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.17.139.193@KRBTEST.COM'} at 127.17.139.193:60133
10:32:34.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:34.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.194 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:34.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.194 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:34.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.17.139.194@KRBTEST.COM; defaulting to no policy
10:32:34.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:34.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.17.139.194@KRBTEST.COM" created.
10:32:34.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:34.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.194 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:34.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.194 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:34.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.622527 17967 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:34.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:34.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/logs
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data/info.pb
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:34.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.194:0
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.139.194
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.194
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.139.254:41819
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:34.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.194
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
10:32:34.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
10:32:34.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
10:32:34.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.974100 18420 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
10:32:34.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.974457 18420 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
10:32:34.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.974507 18420 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
10:32:34.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.974609 18420 flags.cc:432] Enabled unsafe flag: --never_fsync=true
10:32:34.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.983340 18420 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
10:32:34.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:34.983554 18420 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.17.139.194
10:32:34.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.994253 18420 tablet_server_runner.cc:78] Tablet server non-default flags:
10:32:34.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/logs
10:32:34.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.194
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:34.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.194:0
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data/info.pb
10:32:34.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.194
10:32:34.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:34.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.139.254:41819
10:32:34.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
10:32:34.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:34.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
10:32:34.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
10:32:34.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.139.194
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 14880490066cc6e355a232e5a6411307cd134162
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 10:24:06 UTC on bdcb31816ec0
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 17617
10:32:34.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
10:32:34.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.996412 18420 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
10:32:34.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:34.998483 18420 file_cache.cc:504] Constructed file cache file cache with capacity 419430
10:32:35.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.011379 18429 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
10:32:35.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.012723 18430 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
10:32:35.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.016417 18434 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
10:32:35.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.016601 18420 server_base.cc:1061] running on GCE node
10:32:35.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.017567 18420 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
10:32:35.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.018658 18420 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
10:32:35.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.019923 18420 hybrid_clock.cc:648] HybridClock initialized: now 1786444355019838 us; error 54 us; skew 500 ppm
10:32:35.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.020319 18420 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
10:32:35.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:35 dist-test-slave-n326 krb5kdc[17983](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444355, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.139.194@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
10:32:35.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.023896 18420 init.cc:377] Logged in from keytab as kudu/127.17.139.194@KRBTEST.COM (short username kudu)
10:32:35.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.025650 18420 webserver.cc:533] Webserver started at http://127.17.139.194:39931/ using document root <none> and password file <none>
10:32:35.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.026539 18420 fs_manager.cc:362] Metadata directory not provided
10:32:35.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.026679 18420 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
10:32:35.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.027184 18420 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
10:32:35.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.029803 18420 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data/instance:
10:32:35.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0ec5daa5907e4c28888c01780421c7fe"
10:32:35.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:35 on dist-test-slave-n326"
10:32:35.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.030750 18420 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal/instance:
10:32:35.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0ec5daa5907e4c28888c01780421c7fe"
10:32:35.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:35 on dist-test-slave-n326"
10:32:35.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.036537 18420 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.001s	sys 0.003s
10:32:35.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.040390 18449 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
10:32:35.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.041872 18420 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.004s	sys 0.000s
10:32:35.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.042099 18420 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data,/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal
10:32:35.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0ec5daa5907e4c28888c01780421c7fe"
10:32:35.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:35 on dist-test-slave-n326"
10:32:35.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.042353 18420 fs_report.cc:389] FS layout report
10:32:35.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data/data
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
10:32:35.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
10:32:35.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
10:32:35.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
10:32:35.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
10:32:35.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
10:32:35.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
10:32:35.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
10:32:35.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.056972 18420 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
10:32:35.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.061147 18420 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
10:32:35.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.061503 18420 kserver.cc:163] Server-wide thread pool size limit: 3276
10:32:35.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.062589 18420 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
10:32:35.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.064369 18420 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
10:32:35.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.064509 18420 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
10:32:35.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.064627 18420 ts_tablet_manager.cc:616] Registered 0 tablets
10:32:35.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.064715 18420 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
10:32:35.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.102739 18420 rpc_server.cc:307] RPC server started. Bound to: 127.17.139.194:34267
10:32:35.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.102846 18651 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.17.139.194:34267 every 8 connection(s)
10:32:35.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.104449 18420 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/data/info.pb
10:32:35.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.107971 17967 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu as pid 18420
10:32:35.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.108170 17967 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal/instance
10:32:35.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:35 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444355, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.139.194@KRBTEST.COM for kudu/127.17.139.254@KRBTEST.COM
10:32:35.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.128456 18652 heartbeater.cc:344] Connected to a master server at 127.17.139.254:41819
10:32:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.128897 18652 heartbeater.cc:461] Registering TS with master...
10:32:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.17.139.195@KRBTEST.COM; defaulting to no policy
10:32:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.129917 18652 heartbeater.cc:507] Master 127.17.139.254:41819 requested a full tablet report, sending...
10:32:35.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.132012 18072 ts_manager.cc:194] Registered new tserver with Master: 0ec5daa5907e4c28888c01780421c7fe (127.17.139.194:34267)
10:32:35.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:35.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.17.139.195@KRBTEST.COM" created.
10:32:35.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.133140 18072 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.17.139.194@KRBTEST.COM'} at 127.17.139.194:53315
10:32:35.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:35.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.195 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:35.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.17.139.195 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:35.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.17.139.195@KRBTEST.COM; defaulting to no policy
10:32:35.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:35.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.17.139.195@KRBTEST.COM" created.
10:32:35.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
10:32:35.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.195 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:35.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.17.139.195 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab.
10:32:35.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.203390 17967 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:35.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu
10:32:35.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal
10:32:35.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data
10:32:35.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
10:32:35.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/logs
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data/info.pb
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.195:0
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.139.195
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.195
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.139.254:41819
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:35.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.195
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
10:32:35.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
10:32:35.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
10:32:35.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.566428 18406 heartbeater.cc:499] Master 127.17.139.254:41819 was elected leader, sending a full tablet report...
10:32:35.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.568302 18666 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
10:32:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.568728 18666 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
10:32:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.568802 18666 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
10:32:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.568939 18666 flags.cc:432] Enabled unsafe flag: --never_fsync=true
10:32:35.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.577834 18666 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
10:32:35.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.578128 18666 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.17.139.195
10:32:35.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.589370 18666 tablet_server_runner.cc:78] Tablet server non-default flags:
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/logs
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.17.139.212:44513
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
10:32:35.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/kudu.keytab
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.17.139.195
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.17.139.195:0
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
10:32:35.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data/info.pb
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.17.139.195
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.17.139.254:41819
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
10:32:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.17.139.195
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 14880490066cc6e355a232e5a6411307cd134162
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 10:24:06 UTC on bdcb31816ec0
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 17617
10:32:35.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
10:32:35.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.591789 18666 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
10:32:35.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.593918 18666 file_cache.cc:504] Constructed file cache file cache with capacity 419430
10:32:35.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.604537 18673 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
10:32:35.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.604768 18672 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
10:32:35.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:35.604768 18675 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
10:32:35.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.606350 18666 server_base.cc:1061] running on GCE node
10:32:35.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.607223 18666 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
10:32:35.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.608258 18666 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
10:32:35.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.609584 18666 hybrid_clock.cc:648] HybridClock initialized: now 1786444355609499 us; error 43 us; skew 500 ppm
10:32:35.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.610026 18666 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
10:32:35.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:35 dist-test-slave-n326 krb5kdc[17983](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444355, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.139.195@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
10:32:35.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.613852 18666 init.cc:377] Logged in from keytab as kudu/127.17.139.195@KRBTEST.COM (short username kudu)
10:32:35.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.615720 18666 webserver.cc:533] Webserver started at http://127.17.139.195:46123/ using document root <none> and password file <none>
10:32:35.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.616604 18666 fs_manager.cc:362] Metadata directory not provided
10:32:35.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.616758 18666 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
10:32:35.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.617219 18666 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
10:32:35.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.619910 18666 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data/instance:
10:32:35.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "2bb25430b3554f0e807d0967fa691526"
10:32:35.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:35 on dist-test-slave-n326"
10:32:35.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.620848 18666 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal/instance:
10:32:35.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "2bb25430b3554f0e807d0967fa691526"
10:32:35.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:35 on dist-test-slave-n326"
10:32:35.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.626603 18666 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.003s	sys 0.004s
10:32:35.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.630477 18687 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
10:32:35.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.632251 18666 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.000s	sys 0.003s
10:32:35.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.632411 18666 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data,/tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal
10:32:35.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "2bb25430b3554f0e807d0967fa691526"
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 10:32:35 on dist-test-slave-n326"
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.632676 18666 fs_report.cc:389] FS layout report
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data/data
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
10:32:35.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
10:32:35.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
10:32:35.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
10:32:35.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
10:32:35.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
10:32:35.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.660537 18666 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
10:32:35.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.664554 18666 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
10:32:35.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.664788 18666 kserver.cc:163] Server-wide thread pool size limit: 3276
10:32:35.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.665727 18666 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
10:32:35.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.667311 18666 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
10:32:35.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.667402 18666 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
10:32:35.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.667472 18666 ts_tablet_manager.cc:616] Registered 0 tablets
10:32:35.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.667519 18666 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
10:32:35.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.704468 18666 rpc_server.cc:307] RPC server started. Bound to: 127.17.139.195:35093
10:32:35.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.704525 18874 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.17.139.195:35093 every 8 connection(s)
10:32:35.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.706142 18666 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/data/info.pb
10:32:35.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.709347 17967 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu as pid 18666
10:32:35.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.709627 17967 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal/instance
10:32:35.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:35 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444355, etypes {rep=17 tkt=17 ses=17}, kudu/127.17.139.195@KRBTEST.COM for kudu/127.17.139.254@KRBTEST.COM
10:32:35.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.731635 18875 heartbeater.cc:344] Connected to a master server at 127.17.139.254:41819
10:32:35.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.731992 18875 heartbeater.cc:461] Registering TS with master...
10:32:35.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.732945 18875 heartbeater.cc:507] Master 127.17.139.254:41819 requested a full tablet report, sending...
10:32:35.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.734560 18072 ts_manager.cc:194] Registered new tserver with Master: 2bb25430b3554f0e807d0967fa691526 (127.17.139.195:35093)
10:32:35.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.735581 18072 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.17.139.195@KRBTEST.COM'} at 127.17.139.195:55245
10:32:35.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:35.741401 17967 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
10:32:35.744 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
10:32:35.745 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

10:32:35.756 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

10:32:35.765 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.17.139.254"
      port: 41819
    }
    bound_http_address {
      host: "127.17.139.254"
      port: 42295
    }
  }
}

10:32:35.769 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

10:32:35.772 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.17.139.193"
      port: 36971
    }
    bound_http_address {
      host: "127.17.139.193"
      port: 36959
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.17.139.194"
      port: 34267
    }
    bound_http_address {
      host: "127.17.139.194"
      port: 39931
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.17.139.195"
      port: 35093
    }
    bound_http_address {
      host: "127.17.139.195"
      port: 46123
    }
  }
}

10:32:35.772 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
10:32:35.978 [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)
10:32:36.005 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/krb5kdc/krb5cc
10:32:36.070 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
10:32:36.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:36.136140 18652 heartbeater.cc:499] Master 127.17.139.254:41819 was elected leader, sending a full tablet report...
10:32:36.233 [DEBUG - main] (Connection.java:748) [peer master-127.17.139.254:41819(127.17.139.254:41819)] connecting to peer
10:32:36.341 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.17.139.254:41819(127.17.139.254:41819)] Successfully connected to peer
10:32:36.467 [DEBUG - kudu-nio-0] (Negotiator.java:528) SASL mechanism GSSAPI chosen for peer 127.17.139.254
10:32:36.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:36 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (1 etypes {17}) 127.0.0.1: ISSUE: authtime 1786444353, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.17.139.254@KRBTEST.COM
10:32:36.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:36.738478 18875 heartbeater.cc:499] Master 127.17.139.254:41819 was elected leader, sending a full tablet report...
10:32:36.788 [DEBUG - kudu-nio-0] (Negotiator.java:918) Authenticated connection [id: 0x58522c3a, L:/127.0.0.1:47498 - R:/127.17.139.254:41819] using SASL/GSSAPI
10:32:36.833 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
10:32:36.833 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=657), Traces: [0ms] refreshing cache from master, [36ms] Sub RPC ConnectToMaster: sending RPC to server master-127.17.139.254:41819, [624ms] Sub RPC ConnectToMaster: received response from server master-127.17.139.254:41819: OK, deferred=null) after lookup
10:32:36.857 [DEBUG - main] (Connection.java:575) [peer master-127.17.139.254:41819(127.17.139.254:41819)] disconnecting while in state READY
10:32:36.864 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.17.139.254:41819(127.17.139.254:41819)] handling channelInactive
10:32:36.865 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.17.139.254:41819(127.17.139.254:41819)] cleaning up while in state READY due to: connection closed
10:32:36.865 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.17.139.254:41819(127.17.139.254:41819) for tablet Kudu Master: connection closed
10:32:36.879 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.17.139.254:41819(127.17.139.254:41819)] connecting to peer
10:32:36.882 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.17.139.254:41819(127.17.139.254:41819)] Successfully connected to peer
10:32:36.940 [DEBUG - kudu-nio-1] (Negotiator.java:918) Authenticated connection [id: 0xac1b65d3, L:/127.0.0.1:47512 - R:/127.17.139.254:41819] using TOKEN/null
10:32:36.944 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
10:32:36.944 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=86), Traces: [0ms] sending RPC to server master-127.17.139.254:41819, [7ms] received response from server master-127.17.139.254:41819: Network error: connection closed, [7ms] delaying RPC due to: Network error: connection closed, [20ms] refreshing cache from master, [20ms] Sub RPC ConnectToMaster: sending RPC to server master-127.17.139.254:41819, [85ms] Sub RPC ConnectToMaster: received response from server master-127.17.139.254:41819: OK, deferred=Deferred@1798218443(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
10:32:37.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.014029 18072 catalog_manager.cc:2283] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:47512:
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
10:32:37.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
10:32:37.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
10:32:37.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
10:32:37.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:37.021669 18072 catalog_manager.cc:7099] 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.
10:32:37.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.068761 18291 tablet_service.cc:1511] Processing CreateTablet for tablet 579e37bc0df74c40ab4b056b360b3192 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=a1e2e07c880c4f109a929c2ea30274e1]), partition=RANGE (key) PARTITION UNBOUNDED
10:32:37.070 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table a1e2e07c880c4f109a929c2ea30274e1
10:32:37.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.071100 18291 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 579e37bc0df74c40ab4b056b360b3192. 1 dirs total, 0 dirs full, 0 dirs failed
10:32:37.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.081640 18762 tablet_service.cc:1511] Processing CreateTablet for tablet 579e37bc0df74c40ab4b056b360b3192 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=a1e2e07c880c4f109a929c2ea30274e1]), partition=RANGE (key) PARTITION UNBOUNDED
10:32:37.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.083447 18762 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 579e37bc0df74c40ab4b056b360b3192. 1 dirs total, 0 dirs full, 0 dirs failed
10:32:37.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.088382 18526 tablet_service.cc:1511] Processing CreateTablet for tablet 579e37bc0df74c40ab4b056b360b3192 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=a1e2e07c880c4f109a929c2ea30274e1]), partition=RANGE (key) PARTITION UNBOUNDED
10:32:37.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.089956 18526 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 579e37bc0df74c40ab4b056b360b3192. 1 dirs total, 0 dirs full, 0 dirs failed
10:32:37.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.107450 18908 tablet_bootstrap.cc:492] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Bootstrap starting.
10:32:37.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.108078 18910 tablet_bootstrap.cc:492] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Bootstrap starting.
10:32:37.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.113214 18910 tablet_bootstrap.cc:654] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Neither blocks nor log segments found. Creating new log.
10:32:37.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.113214 18908 tablet_bootstrap.cc:654] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Neither blocks nor log segments found. Creating new log.
10:32:37.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.114763 18913 tablet_bootstrap.cc:492] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Bootstrap starting.
10:32:37.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.114771 18908 log.cc:826] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Log is configured to *not* fsync() on all Append() calls
10:32:37.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.116858 18910 log.cc:826] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Log is configured to *not* fsync() on all Append() calls
10:32:37.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.118959 18913 tablet_bootstrap.cc:654] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Neither blocks nor log segments found. Creating new log.
10:32:37.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.120635 18913 log.cc:826] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Log is configured to *not* fsync() on all Append() calls
10:32:37.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.125705 18910 tablet_bootstrap.cc:492] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: No bootstrap required, opened a new log
10:32:37.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.126423 18910 ts_tablet_manager.cc:1403] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Time spent bootstrapping tablet: real 0.019s	user 0.007s	sys 0.006s
10:32:37.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.126713 18908 tablet_bootstrap.cc:492] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: No bootstrap required, opened a new log
10:32:37.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.127236 18908 ts_tablet_manager.cc:1403] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Time spent bootstrapping tablet: real 0.020s	user 0.008s	sys 0.003s
10:32:37.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.134865 18908 raft_consensus.cc:359] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.136047 18908 raft_consensus.cc:385] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
10:32:37.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.136504 18908 raft_consensus.cc:740] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 52a9ee62f26e406798dbd7d654ce55d2, State: Initialized, Role: FOLLOWER
10:32:37.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.137501 18913 tablet_bootstrap.cc:492] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: No bootstrap required, opened a new log
10:32:37.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.137881 18913 ts_tablet_manager.cc:1403] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Time spent bootstrapping tablet: real 0.023s	user 0.006s	sys 0.010s
10:32:37.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.139495 18910 raft_consensus.cc:359] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.139978 18910 raft_consensus.cc:385] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
10:32:37.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.140098 18910 raft_consensus.cc:740] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 2bb25430b3554f0e807d0967fa691526, State: Initialized, Role: FOLLOWER
10:32:37.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.140949 18910 consensus_queue.cc:260] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [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: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.137560 18908 consensus_queue.cc:260] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [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: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.148080 18908 ts_tablet_manager.cc:1434] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Time spent starting tablet: real 0.020s	user 0.012s	sys 0.001s
10:32:37.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.151057 18913 raft_consensus.cc:359] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.152006 18913 raft_consensus.cc:385] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
10:32:37.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.152051 18910 ts_tablet_manager.cc:1434] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Time spent starting tablet: real 0.020s	user 0.009s	sys 0.007s
10:32:37.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.152618 18913 raft_consensus.cc:740] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0ec5daa5907e4c28888c01780421c7fe, State: Initialized, Role: FOLLOWER
10:32:37.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.153709 18913 consensus_queue.cc:260] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [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: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.157229 18913 ts_tablet_manager.cc:1434] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Time spent starting tablet: real 0.019s	user 0.006s	sys 0.008s
10:32:37.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:37.210280 18876 tablet.cc:2406] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Can't schedule compaction. Clean time has not been advanced past its initial value.
10:32:37.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.248941 18924 raft_consensus.cc:493] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
10:32:37.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.249315 18924 raft_consensus.cc:515] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.251499 18924 leader_election.cc:290] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 2bb25430b3554f0e807d0967fa691526 (127.17.139.195:35093), 52a9ee62f26e406798dbd7d654ce55d2 (127.17.139.193:36971)
10:32:37.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.262235 18795 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "579e37bc0df74c40ab4b056b360b3192" candidate_uuid: "0ec5daa5907e4c28888c01780421c7fe" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "2bb25430b3554f0e807d0967fa691526" is_pre_election: true
10:32:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.262283 18334 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "579e37bc0df74c40ab4b056b360b3192" candidate_uuid: "0ec5daa5907e4c28888c01780421c7fe" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "52a9ee62f26e406798dbd7d654ce55d2" is_pre_election: true
10:32:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.262831 18334 raft_consensus.cc:2468] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 0ec5daa5907e4c28888c01780421c7fe in term 0.
10:32:37.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.262837 18795 raft_consensus.cc:2468] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 0ec5daa5907e4c28888c01780421c7fe in term 0.
10:32:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.263666 18453 leader_election.cc:304] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [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: 0ec5daa5907e4c28888c01780421c7fe, 52a9ee62f26e406798dbd7d654ce55d2; no voters: 
10:32:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.264159 18924 raft_consensus.cc:2804] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Leader pre-election won for term 1
10:32:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.264333 18924 raft_consensus.cc:493] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
10:32:37.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.264460 18924 raft_consensus.cc:3060] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 0 FOLLOWER]: Advancing to term 1
10:32:37.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.266602 18924 raft_consensus.cc:515] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.267447 18924 leader_election.cc:290] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [CANDIDATE]: Term 1 election: Requested vote from peers 2bb25430b3554f0e807d0967fa691526 (127.17.139.195:35093), 52a9ee62f26e406798dbd7d654ce55d2 (127.17.139.193:36971)
10:32:37.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.267930 18795 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "579e37bc0df74c40ab4b056b360b3192" candidate_uuid: "0ec5daa5907e4c28888c01780421c7fe" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "2bb25430b3554f0e807d0967fa691526"
10:32:37.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.268035 18334 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "579e37bc0df74c40ab4b056b360b3192" candidate_uuid: "0ec5daa5907e4c28888c01780421c7fe" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "52a9ee62f26e406798dbd7d654ce55d2"
10:32:37.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.268162 18795 raft_consensus.cc:3060] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 0 FOLLOWER]: Advancing to term 1
10:32:37.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.268290 18334 raft_consensus.cc:3060] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 0 FOLLOWER]: Advancing to term 1
10:32:37.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:37.270589 18408 tablet.cc:2406] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Can't schedule compaction. Clean time has not been advanced past its initial value.
10:32:37.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.270610 18334 raft_consensus.cc:2468] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 0ec5daa5907e4c28888c01780421c7fe in term 1.
10:32:37.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.271215 18795 raft_consensus.cc:2468] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 0ec5daa5907e4c28888c01780421c7fe in term 1.
10:32:37.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.271236 18453 leader_election.cc:304] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [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: 0ec5daa5907e4c28888c01780421c7fe, 52a9ee62f26e406798dbd7d654ce55d2; no voters: 
10:32:37.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.271624 18924 raft_consensus.cc:2804] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 1 FOLLOWER]: Leader election won for term 1
10:32:37.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.272256 18924 raft_consensus.cc:697] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 1 LEADER]: Becoming Leader. State: Replica: 0ec5daa5907e4c28888c01780421c7fe, State: Running, Role: LEADER
10:32:37.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.272799 18924 consensus_queue.cc:237] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [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: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } }
10:32:37.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.277989 18071 catalog_manager.cc:5719] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe reported cstate change: term changed from 0 to 1, leader changed from <none> to 0ec5daa5907e4c28888c01780421c7fe (127.17.139.194). New cstate: current_term: 1 leader_uuid: "0ec5daa5907e4c28888c01780421c7fe" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "0ec5daa5907e4c28888c01780421c7fe" member_type: VOTER last_known_addr { host: "127.17.139.194" port: 34267 } health_report { overall_health: HEALTHY } } }
10:32:37.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:37.358042 18653 tablet.cc:2406] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Can't schedule compaction. Clean time has not been advanced past its initial value.
10:32:37.367 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table a1e2e07c880c4f109a929c2ea30274e1
10:32:37.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.781983 18933 consensus_queue.cc:1048] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [LEADER]: Connected to new peer: Peer: permanent_uuid: "52a9ee62f26e406798dbd7d654ce55d2" member_type: VOTER last_known_addr { host: "127.17.139.193" port: 36971 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
10:32:37.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:37.792083 18937 consensus_queue.cc:1048] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [LEADER]: Connected to new peer: Peer: permanent_uuid: "2bb25430b3554f0e807d0967fa691526" member_type: VOTER last_known_addr { host: "127.17.139.195" port: 35093 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
10:32:38.372 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table a1e2e07c880c4f109a929c2ea30274e1
10:32:38.397 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 579e37bc0df74c40ab4b056b360b3192 for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
10:32:38.403 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299993, tablet=579e37bc0df74c40ab4b056b360b3192@[0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)[L],2bb25430b3554f0e807d0967fa691526(127.17.139.195:35093),52a9ee62f26e406798dbd7d654ce55d2(127.17.139.193:36971)]}]
10:32:38.413 [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.17.139.254:41819, [8ms] Sub RPC GetTableLocations: received response from server master-127.17.139.254:41819: OK, deferred=null)) after lookup
10:32:38.428 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] connecting to peer
10:32:38.431 [DEBUG - kudu-nio-2] (Connection.java:757) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] Successfully connected to peer
10:32:38.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:38.485581 18950 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:43732: token expired
10:32:38.490 [DEBUG - kudu-nio-2] (Negotiator.java:351) peer /127.17.139.194:34267 sent connection negotiation error: Not authorized: token expired
10:32:38.493 [DEBUG - kudu-nio-2] (Connection.java:239) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] handling channelInactive
10:32:38.493 [DEBUG - kudu-nio-2] (Connection.java:709) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
10:32:38.496 [DEBUG - kudu-nio-2] (Connection.java:748) [peer master-127.17.139.254:41819(127.17.139.254:41819)] connecting to peer
10:32:38.499 [DEBUG - kudu-nio-3] (Connection.java:757) [peer master-127.17.139.254:41819(127.17.139.254:41819)] Successfully connected to peer
10:32:38.509 [DEBUG - kudu-nio-3] (Negotiator.java:528) SASL mechanism GSSAPI chosen for peer 127.17.139.254
10:32:38.557 [DEBUG - kudu-nio-3] (Negotiator.java:918) Authenticated connection [id: 0x66637f30, L:/127.0.0.1:47520 - R:/127.17.139.254:41819] using SASL/GSSAPI
10:32:38.560 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
10:32:38.561 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] connecting to peer
10:32:38.563 [DEBUG - kudu-nio-4] (Connection.java:757) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] Successfully connected to peer
10:32:38.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:38.589478 18950 fault_injection.cc:43] FAULT INJECTION ENABLED!
10:32:38.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:38.589636 18950 fault_injection.cc:44] THIS SERVER MAY CRASH!
10:32:38.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:38.590162 18950 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:43746: token signing key expired
10:32:38.591 [DEBUG - kudu-nio-4] (Negotiator.java:351) peer /127.17.139.194:34267 sent connection negotiation error: Not authorized: token signing key expired
10:32:38.592 [DEBUG - kudu-nio-4] (Connection.java:239) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] handling channelInactive
10:32:38.592 [DEBUG - kudu-nio-4] (Connection.java:709) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
10:32:38.595 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
10:32:38.595 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] connecting to peer
10:32:38.597 [DEBUG - kudu-nio-5] (Connection.java:757) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] Successfully connected to peer
10:32:38.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:38.630551 18950 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:43754: invalid token signature
10:32:38.632 [DEBUG - kudu-nio-5] (Negotiator.java:351) peer /127.17.139.194:34267 sent connection negotiation error: Not authorized: invalid token signature
10:32:38.633 [DEBUG - kudu-nio-5] (Connection.java:239) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] handling channelInactive
10:32:38.633 [DEBUG - kudu-nio-5] (Connection.java:709) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
10:32:38.637 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
10:32:38.637 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] connecting to peer
10:32:38.641 [DEBUG - kudu-nio-6] (Connection.java:757) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] Successfully connected to peer
10:32:38.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:38.670712 18950 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:43756: token signing key expired
10:32:38.672 [DEBUG - kudu-nio-6] (Negotiator.java:351) peer /127.17.139.194:34267 sent connection negotiation error: Not authorized: token signing key expired
10:32:38.673 [DEBUG - kudu-nio-6] (Connection.java:239) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] handling channelInactive
10:32:38.674 [DEBUG - kudu-nio-6] (Connection.java:709) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
10:32:38.677 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
10:32:38.678 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] connecting to peer
10:32:38.681 [DEBUG - kudu-nio-7] (Connection.java:757) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] Successfully connected to peer
10:32:38.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 10:32:38.729543 18950 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:43770: token expired
10:32:38.732 [DEBUG - kudu-nio-7] (Negotiator.java:351) peer /127.17.139.194:34267 sent connection negotiation error: Not authorized: token expired
10:32:38.734 [DEBUG - kudu-nio-7] (Connection.java:239) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] handling channelInactive
10:32:38.734 [DEBUG - kudu-nio-7] (Connection.java:709) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
10:32:38.742 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
10:32:38.742 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] connecting to peer
10:32:38.744 [DEBUG - kudu-nio-0] (Connection.java:757) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] Successfully connected to peer
10:32:38.764 [DEBUG - kudu-nio-0] (Negotiator.java:918) Authenticated connection [id: 0xfafbfd66, L:/127.0.0.1:43774 - R:/127.17.139.194:34267] using TOKEN/null
10:32:38.791 [DEBUG - kudu-nio-0] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=579e37bc0df74c40ab4b056b360b3192, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
10:32:38.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.809788 18072 catalog_manager.cc:2533] Servicing SoftDeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:47520:
10:32:38.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
10:32:38.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.810073 18072 catalog_manager.cc:2781] Servicing DeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:47520:
10:32:38.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
10:32:38.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.818112 18072 catalog_manager.cc:6006] T 00000000000000000000000000000000 P 506b17e222db4681b1c39f45a2d69200: Sending DeleteTablet for 3 replicas of tablet 579e37bc0df74c40ab4b056b360b3192
10:32:38.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.819972 18526 tablet_service.cc:1558] Processing DeleteTablet for tablet 579e37bc0df74c40ab4b056b360b3192 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-11 10:32:38 UTC) from {username='kudu', principal='kudu/127.17.139.254@KRBTEST.COM'} at 127.0.0.1:43726
10:32:38.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.820101 18291 tablet_service.cc:1558] Processing DeleteTablet for tablet 579e37bc0df74c40ab4b056b360b3192 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-11 10:32:38 UTC) from {username='kudu', principal='kudu/127.17.139.254@KRBTEST.COM'} at 127.0.0.1:34894
10:32:38.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.820737 18762 tablet_service.cc:1558] Processing DeleteTablet for tablet 579e37bc0df74c40ab4b056b360b3192 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-11 10:32:38 UTC) from {username='kudu', principal='kudu/127.17.139.254@KRBTEST.COM'} at 127.0.0.1:36264
10:32:38.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.829277 18969 tablet_replica.cc:333] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: stopping tablet replica
10:32:38.843 [DEBUG - main] (Connection.java:575) [peer master-127.17.139.254:41819(127.17.139.254:41819)] disconnecting while in state READY
10:32:38.844 [DEBUG - main] (Connection.java:575) [peer master-127.17.139.254:41819(127.17.139.254:41819)] disconnecting while in state READY
10:32:38.844 [DEBUG - main] (Connection.java:575) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] disconnecting while in state READY
10:32:38.845 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.17.139.254:41819(127.17.139.254:41819)] handling channelInactive
10:32:38.845 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.17.139.254:41819(127.17.139.254:41819)] cleaning up while in state READY due to: connection closed
10:32:38.846 [DEBUG - kudu-nio-0] (Connection.java:239) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] handling channelInactive
10:32:38.846 [DEBUG - kudu-nio-0] (Connection.java:709) [peer 0ec5daa5907e4c28888c01780421c7fe(127.17.139.194:34267)] cleaning up while in state READY due to: connection closed
10:32:38.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.850304 18969 raft_consensus.cc:2243] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 1 FOLLOWER]: Raft consensus shutting down.
10:32:38.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.850942 18969 raft_consensus.cc:2272] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526 [term 1 FOLLOWER]: Raft consensus is shut down!
10:32:38.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.853040 18969 ts_tablet_manager.cc:1916] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Deleting tablet data with delete state TABLET_DATA_DELETED
10:32:38.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.855883 18967 tablet_replica.cc:333] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: stopping tablet replica
10:32:38.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.855914 18968 tablet_replica.cc:333] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: stopping tablet replica
10:32:38.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.856383 18967 raft_consensus.cc:2243] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 1 LEADER]: Raft consensus shutting down.
10:32:38.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.856863 18968 raft_consensus.cc:2243] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 1 FOLLOWER]: Raft consensus shutting down.
10:32:38.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.857170 18967 raft_consensus.cc:2272] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe [term 1 FOLLOWER]: Raft consensus is shut down!
10:32:38.859 [DEBUG - kudu-nio-3] (AsyncKuduClient.java:2704) Releasing all remaining resources
10:32:38.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.857366 18968 raft_consensus.cc:2272] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2 [term 1 FOLLOWER]: Raft consensus is shut down!
10:32:38.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.858683 18967 ts_tablet_manager.cc:1916] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Deleting tablet data with delete state TABLET_DATA_DELETED
10:32:38.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.858807 18968 ts_tablet_manager.cc:1916] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Deleting tablet data with delete state TABLET_DATA_DELETED
10:32:38.863 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.863570 17967 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu pid 18182
10:32:38.864 [DEBUG - kudu-nio-3] (Connection.java:239) [peer master-127.17.139.254:41819(127.17.139.254:41819)] handling channelInactive
10:32:38.864 [DEBUG - kudu-nio-3] (Connection.java:709) [peer master-127.17.139.254:41819(127.17.139.254:41819)] cleaning up while in state READY due to: connection closed
10:32:38.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.864825 18969 ts_tablet_manager.cc:1929] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
10:32:38.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.865233 18969 log.cc:1199] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Deleting WAL directory at /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-2/wal/wals/579e37bc0df74c40ab4b056b360b3192
10:32:38.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.866688 18969 ts_tablet_manager.cc:1950] T 579e37bc0df74c40ab4b056b360b3192 P 2bb25430b3554f0e807d0967fa691526: Deleting consensus metadata
10:32:38.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.869534 18050 catalog_manager.cc:5050] TS 2bb25430b3554f0e807d0967fa691526 (127.17.139.195:35093): tablet 579e37bc0df74c40ab4b056b360b3192 (table TestAuthnTokenReacquireOpen-table [id=a1e2e07c880c4f109a929c2ea30274e1]) successfully deleted
10:32:38.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.872463 18968 ts_tablet_manager.cc:1929] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
10:32:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.872888 18968 log.cc:1199] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Deleting WAL directory at /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-0/wal/wals/579e37bc0df74c40ab4b056b360b3192
10:32:38.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.873687 18968 ts_tablet_manager.cc:1950] T 579e37bc0df74c40ab4b056b360b3192 P 52a9ee62f26e406798dbd7d654ce55d2: Deleting consensus metadata
10:32:38.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.875043 18967 ts_tablet_manager.cc:1929] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
10:32:38.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.875500 18967 log.cc:1199] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Deleting WAL directory at /tmp/dist-test-taskCtpRxu/test-tmp/mini-kudu-cluster202037311436910475/ts-1/wal/wals/579e37bc0df74c40ab4b056b360b3192
10:32:38.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.876066 18049 catalog_manager.cc:5050] TS 52a9ee62f26e406798dbd7d654ce55d2 (127.17.139.193:36971): tablet 579e37bc0df74c40ab4b056b360b3192 (table TestAuthnTokenReacquireOpen-table [id=a1e2e07c880c4f109a929c2ea30274e1]) successfully deleted
10:32:38.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.876374 18967 ts_tablet_manager.cc:1950] T 579e37bc0df74c40ab4b056b360b3192 P 0ec5daa5907e4c28888c01780421c7fe: Deleting consensus metadata
10:32:38.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.878249 18050 catalog_manager.cc:5050] TS 0ec5daa5907e4c28888c01780421c7fe (127.17.139.194:34267): tablet 579e37bc0df74c40ab4b056b360b3192 (table TestAuthnTokenReacquireOpen-table [id=a1e2e07c880c4f109a929c2ea30274e1]) successfully deleted
10:32:38.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:38 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444353, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.17.139.193@KRBTEST.COM
10:32:38.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.888419 18401 generic_service.cc:196] Checking for leaks (request via RPC)
10:32:38.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:38.985591 17967 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu with pid 18182
10:32:39.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.022719 17967 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu pid 18420
10:32:39.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:39 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444353, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.17.139.194@KRBTEST.COM
10:32:39.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.040578 18641 generic_service.cc:196] Checking for leaks (request via RPC)
10:32:39.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.157089 17967 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu with pid 18420
10:32:39.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.191206 17967 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu pid 18666
10:32:39.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 10:32:39 dist-test-slave-n326 krb5kdc[17983](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786444353, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.17.139.195@KRBTEST.COM
10:32:39.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.208526 18870 generic_service.cc:196] Checking for leaks (request via RPC)
10:32:39.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.302403 17967 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu with pid 18666
10:32:39.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.327183 17967 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu pid 18013
10:32:39.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.328436 18121 generic_service.cc:196] Checking for leaks (request via RPC)
10:32:39.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 10:32:39.443104 17967 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskCtpRxu/build/asan/bin/kudu with pid 18013
10:32:39.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T10:32:39Z chronyd exiting

Time: 9.46

OK (1 test)

