JUnit version 4.13.2
.18:29:13.532 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1783535353522
18:29:13.571 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
18:29:13.606 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
18:29:13.615 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskrqG6ze/test-tmp
18:29:13.641 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/asan/bin
18:29:13.644 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskrqG6ze/java/build/dist-test/../../../build/asan/bin/kudu, test, mini_cluster, --serialization=pb]
18:29:13.844 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208"
  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 {
  }
}

18:29:14.090 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
18:29:14.092 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

18:29:14.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:14.095269  3648 mini_oidc.cc:150] Starting JWKS server
18:29:14.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:14.098191  3648 webserver.cc:534] Webserver started at http://127.0.0.1:35151/ using document root <none> and password file <none>
18:29:14.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:14.098500  3648 mini_oidc.cc:186] Starting OIDC Discovery server
18:29:14.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:14.099922  3648 webserver.cc:534] Webserver started at http://127.0.0.1:35043/ using document root <none> and password file <none>
18:29:14.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
18:29:14.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/principal' for realm 'KRBTEST.COM',
18:29:14.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
18:29:14.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:14 dist-test-slave-9gpt krb5kdc[3658](info): setting up network...
18:29:14.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
18:29:14.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:14 dist-test-slave-9gpt krb5kdc[3658](info): set up 2 sockets
18:29:14.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:14 dist-test-slave-9gpt krb5kdc[3658](info): commencing operation
18:29:14.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
18:29:16.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.190346  3648 mini_kdc.cc:121] Time spent starting KDC: real 2.082s	user 0.000s	sys 0.018s
18:29:16.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
18:29:16.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
18:29:16.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
18:29:16.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
18:29:16.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
18:29:16.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
18:29:16.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
18:29:16.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
18:29:16.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
18:29:16.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
18:29:16.301 [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-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/test-user.keytab.
18:29:16.302 [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-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/test-user.keytab.
18:29:16.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:16 dist-test-slave-9gpt krb5kdc[3658](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535356, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
18:29:16.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
18:29:16.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T18:29:16Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
18:29:16.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T18:29:16Z Disabled control of system clock
18:29:16.367 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.144.62@KRBTEST.COM; defaulting to no policy
18:29:16.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:16.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.144.62@KRBTEST.COM" created.
18:29:16.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:16.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:16.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:16.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.144.62@KRBTEST.COM; defaulting to no policy
18:29:16.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:16.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.144.62@KRBTEST.COM" created.
18:29:16.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:16.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:16.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:16.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.439185  3648 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:16.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:16.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/wal
18:29:16.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data
18:29:16.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:29:16.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:29:16.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:29:16.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:16.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:16.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:16.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/logs
18:29:16.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data/info.pb
18:29:16.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:16.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:29:16.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:29:16.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:29:16.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:29:16.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:16.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:16.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:16.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
18:29:16.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:29:16.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:29:16.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:29:16.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.62:33203
18:29:16.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.62
18:29:16.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:16.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:16.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.62
18:29:16.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:16.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
18:29:16.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
18:29:16.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
18:29:16.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:16.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:16.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:16.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:16.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:29:16.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.3.144.62:33203
18:29:16.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:29:16.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35151/jwks.json
18:29:16.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
18:29:16.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
18:29:16.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
18:29:16.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.792690  3674 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
18:29:16.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.793038  3674 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:29:16.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.793133  3674 flags.cc:432] Enabled unsafe flag: --never_fsync=true
18:29:16.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.802044  3674 flags.cc:432] Enabled experimental flag: --authn_token_validity_seconds=1
18:29:16.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.802145  3674 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
18:29:16.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.802211  3674 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
18:29:16.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.802261  3674 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
18:29:16.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.802323  3674 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
18:29:16.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.802364  3674 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
18:29:16.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.802462  3674 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:35151/jwks.json
18:29:16.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.814898  3674 master_runner.cc:387] Master server non-default flags:
18:29:16.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:16.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:16.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:16.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:16.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data
18:29:16.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/wal
18:29:16.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
18:29:16.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
18:29:16.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.3.144.62:33203
18:29:16.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:16.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.62
18:29:16.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:16.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:16.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
18:29:16.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.62:33203
18:29:16.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
18:29:16.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:29:16.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
18:29:16.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35151/jwks.json
18:29:16.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:16.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
18:29:16.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
18:29:16.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
18:29:16.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:16.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:16.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data/info.pb
18:29:16.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.62
18:29:16.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:16.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:29:16.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:16.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:29:16.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/logs
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
18:29:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 3ddc7d4404ac90c834b1cdd020973c286611e9b0
18:29:16.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
18:29:16.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 18:01:24 UTC on bdcb31816ec0
18:29:16.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16870
18:29:16.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
18:29:16.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.816891  3674 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:29:16.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.818555  3674 file_cache.cc:492] Constructed file cache file cache with capacity 419430
18:29:16.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.829084  3680 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
18:29:16.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.829058  3679 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
18:29:16.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:16.829159  3682 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
18:29:16.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.829448  3674 server_base.cc:1061] running on GCE node
18:29:16.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.830673  3674 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:29:16.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.832170  3674 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:29:16.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.833453  3674 hybrid_clock.cc:648] HybridClock initialized: now 1783535356833374 us; error 59 us; skew 500 ppm
18:29:16.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.833834  3674 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
18:29:16.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:16 dist-test-slave-9gpt krb5kdc[3658](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535356, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.144.62@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
18:29:16.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.837378  3674 init.cc:377] Logged in from keytab as kudu/127.3.144.62@KRBTEST.COM (short username kudu)
18:29:16.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.839138  3674 webserver.cc:534] Webserver started at http://127.3.144.62:36273/ using document root <none> and password file <none>
18:29:16.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.840129  3674 fs_manager.cc:362] Metadata directory not provided
18:29:16.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.840268  3674 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:29:16.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.840675  3674 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
18:29:16.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.843330  3674 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data/instance:
18:29:16.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c06f6dce11544c01a05d672d9ca97178"
18:29:16.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:16 on dist-test-slave-9gpt"
18:29:16.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.844247  3674 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/wal/instance:
18:29:16.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c06f6dce11544c01a05d672d9ca97178"
18:29:16.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:16 on dist-test-slave-9gpt"
18:29:16.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.850680  3674 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.003s	sys 0.005s
18:29:16.855 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.854878  3689 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:29:16.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.856796  3674 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.004s	sys 0.000s
18:29:16.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.856985  3674 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data,/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/wal
18:29:16.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c06f6dce11544c01a05d672d9ca97178"
18:29:16.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:16 on dist-test-slave-9gpt"
18:29:16.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.857183  3674 fs_report.cc:389] FS layout report
18:29:16.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:29:16.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/wal
18:29:16.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/wal
18:29:16.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data/data
18:29:16.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:29:16.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:29:16.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:29:16.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:29:16.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:29:16.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:29:16.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:29:16.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:29:16.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:29:16.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:29:16.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:29:16.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.882211  3674 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
18:29:16.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.886801  3674 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:29:16.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.887053  3674 kserver.cc:163] Server-wide thread pool size limit: 3276
18:29:16.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.910833  3674 rpc_server.cc:307] RPC server started. Bound to: 127.3.144.62:33203
18:29:16.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.910858  3740 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.144.62:33203 every 8 connection(s)
18:29:16.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.912463  3674 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/data/info.pb
18:29:16.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.918190  3741 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
18:29:16.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.921119  3648 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu as pid 3674
18:29:16.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.921456  3648 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/master-0/wal/instance
18:29:16.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.933398  3741 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178: Bootstrap starting.
18:29:16.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.939539  3741 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178: Neither blocks nor log segments found. Creating new log.
18:29:16.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.941663  3741 log.cc:826] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178: Log is configured to *not* fsync() on all Append() calls
18:29:16.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.947118  3741 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178: No bootstrap required, opened a new log
18:29:16.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.953401  3741 raft_consensus.cc:359] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c06f6dce11544c01a05d672d9ca97178" member_type: VOTER last_known_addr { host: "127.3.144.62" port: 33203 } }
18:29:16.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.953876  3741 raft_consensus.cc:385] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:29:16.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.953971  3741 raft_consensus.cc:740] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c06f6dce11544c01a05d672d9ca97178, State: Initialized, Role: FOLLOWER
18:29:16.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.954838  3741 consensus_queue.cc:260] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [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: "c06f6dce11544c01a05d672d9ca97178" member_type: VOTER last_known_addr { host: "127.3.144.62" port: 33203 } }
18:29:16.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.955114  3741 raft_consensus.cc:399] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
18:29:16.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.955278  3741 raft_consensus.cc:493] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
18:29:16.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.955489  3741 raft_consensus.cc:3060] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 0 FOLLOWER]: Advancing to term 1
18:29:16.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.959357  3741 raft_consensus.cc:515] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c06f6dce11544c01a05d672d9ca97178" member_type: VOTER last_known_addr { host: "127.3.144.62" port: 33203 } }
18:29:16.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.960103  3741 leader_election.cc:304] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [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: c06f6dce11544c01a05d672d9ca97178; no voters: 
18:29:16.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.960983  3741 leader_election.cc:290] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [CANDIDATE]: Term 1 election: Requested vote from peers 
18:29:16.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.961125  3746 raft_consensus.cc:2804] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 1 FOLLOWER]: Leader election won for term 1
18:29:16.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:16 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535356, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.3.144.62@KRBTEST.COM
18:29:16.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.965327  3746 raft_consensus.cc:697] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [term 1 LEADER]: Becoming Leader. State: Replica: c06f6dce11544c01a05d672d9ca97178, State: Running, Role: LEADER
18:29:16.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.966506  3746 consensus_queue.cc:237] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [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: "c06f6dce11544c01a05d672d9ca97178" member_type: VOTER last_known_addr { host: "127.3.144.62" port: 33203 } }
18:29:16.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.968355  3741 sys_catalog.cc:565] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [sys.catalog]: configured and running, proceeding with master startup.
18:29:16.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.971266  3748 sys_catalog.cc:455] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "c06f6dce11544c01a05d672d9ca97178" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c06f6dce11544c01a05d672d9ca97178" member_type: VOTER last_known_addr { host: "127.3.144.62" port: 33203 } } }
18:29:16.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.971618  3748 sys_catalog.cc:458] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [sys.catalog]: This master's current role is: LEADER
18:29:16.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.971367  3747 sys_catalog.cc:455] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [sys.catalog]: SysCatalogTable state changed. Reason: New leader c06f6dce11544c01a05d672d9ca97178. Latest consensus state: current_term: 1 leader_uuid: "c06f6dce11544c01a05d672d9ca97178" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "c06f6dce11544c01a05d672d9ca97178" member_type: VOTER last_known_addr { host: "127.3.144.62" port: 33203 } } }
18:29:16.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.971760  3747 sys_catalog.cc:458] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178 [sys.catalog]: This master's current role is: LEADER
18:29:16.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.975481  3751 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
18:29:16.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:16.989426  3751 catalog_manager.cc:1519] Initializing Kudu cluster ID...
18:29:17.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.001395  3751 catalog_manager.cc:1382] Generated new cluster ID: 8266544be2f74518a10d0e6afb3ab22d
18:29:17.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.001667  3751 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
18:29:17.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.019426  3751 catalog_manager.cc:1405] Generated new certificate authority record
18:29:17.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.021107  3751 catalog_manager.cc:1539] Loading token signing keys...
18:29:17.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.040917  3751 catalog_manager.cc:6069] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178: Generated new TSK 0
18:29:17.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.042259  3751 catalog_manager.cc:1549] Initializing in-progress tserver states...
18:29:17.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.144.1@KRBTEST.COM; defaulting to no policy
18:29:17.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.144.1@KRBTEST.COM" created.
18:29:17.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.144.1@KRBTEST.COM; defaulting to no policy
18:29:17.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.144.1@KRBTEST.COM" created.
18:29:17.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.139966  3648 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:17.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:17.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal
18:29:17.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data
18:29:17.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:29:17.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:29:17.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:29:17.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/logs
18:29:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data/info.pb
18:29:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:17.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:29:17.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:29:17.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:29:17.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:29:17.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:17.144 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:17.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:17.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
18:29:17.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:29:17.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.1:0
18:29:17.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.144.1
18:29:17.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.1
18:29:17.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:17.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.144.62:33203
18:29:17.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:17.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.1
18:29:17.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:17.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
18:29:17.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
18:29:17.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
18:29:17.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:17.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:17.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:17.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:17.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
18:29:17.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
18:29:17.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
18:29:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.511650  3769 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
18:29:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.512069  3769 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
18:29:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.512142  3769 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:29:17.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.512288  3769 flags.cc:432] Enabled unsafe flag: --never_fsync=true
18:29:17.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.523305  3769 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
18:29:17.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.523692  3769 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.3.144.1
18:29:17.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.536502  3769 tablet_server_runner.cc:78] Tablet server non-default flags:
18:29:17.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:17.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:17.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:17.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:17.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data
18:29:17.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal
18:29:17.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
18:29:17.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:17.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.1
18:29:17.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:17.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:17.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.1:0
18:29:17.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:29:17.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:17.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
18:29:17.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
18:29:17.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
18:29:17.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:17.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:17.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data/info.pb
18:29:17.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.1
18:29:17.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.144.62:33203
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.144.1
18:29:17.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/logs
18:29:17.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:17.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:29:17.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:29:17.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
18:29:17.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
18:29:17.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 3ddc7d4404ac90c834b1cdd020973c286611e9b0
18:29:17.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
18:29:17.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 18:01:24 UTC on bdcb31816ec0
18:29:17.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16870
18:29:17.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
18:29:17.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.539048  3769 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:29:17.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.541536  3769 file_cache.cc:492] Constructed file cache file cache with capacity 419430
18:29:17.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.553503  3774 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
18:29:17.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.553448  3775 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
18:29:17.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:17.559368  3777 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
18:29:17.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.560343  3769 server_base.cc:1061] running on GCE node
18:29:17.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.561101  3769 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:29:17.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.562229  3769 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:29:17.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.563503  3769 hybrid_clock.cc:648] HybridClock initialized: now 1783535357563423 us; error 60 us; skew 500 ppm
18:29:17.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.563987  3769 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
18:29:17.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:17 dist-test-slave-9gpt krb5kdc[3658](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535357, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.144.1@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
18:29:17.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.568204  3769 init.cc:377] Logged in from keytab as kudu/127.3.144.1@KRBTEST.COM (short username kudu)
18:29:17.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.571162  3769 webserver.cc:534] Webserver started at http://127.3.144.1:34975/ using document root <none> and password file <none>
18:29:17.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.572193  3769 fs_manager.cc:362] Metadata directory not provided
18:29:17.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.572302  3769 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:29:17.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.572677  3769 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
18:29:17.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.575529  3769 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data/instance:
18:29:17.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "84e94e29ec9d44728804b9830a754746"
18:29:17.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:17 on dist-test-slave-9gpt"
18:29:17.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.576558  3769 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal/instance:
18:29:17.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "84e94e29ec9d44728804b9830a754746"
18:29:17.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:17 on dist-test-slave-9gpt"
18:29:17.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.584965  3769 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.003s	sys 0.003s
18:29:17.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.590121  3784 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:29:17.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.591975  3769 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.001s	sys 0.003s
18:29:17.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.592163  3769 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data,/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal
18:29:17.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "84e94e29ec9d44728804b9830a754746"
18:29:17.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:17 on dist-test-slave-9gpt"
18:29:17.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.592350  3769 fs_report.cc:389] FS layout report
18:29:17.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:29:17.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal
18:29:17.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal
18:29:17.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data/data
18:29:17.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:29:17.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:29:17.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:29:17.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:29:17.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:29:17.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:29:17.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:29:17.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:29:17.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:29:17.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:29:17.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:29:17.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.620805  3769 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
18:29:17.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.625993  3769 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:29:17.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.626291  3769 kserver.cc:163] Server-wide thread pool size limit: 3276
18:29:17.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.627349  3769 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
18:29:17.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.629191  3769 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
18:29:17.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.629284  3769 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
18:29:17.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.629360  3769 ts_tablet_manager.cc:616] Registered 0 tablets
18:29:17.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.629438  3769 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
18:29:17.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.670393  3769 rpc_server.cc:307] RPC server started. Bound to: 127.3.144.1:36745
18:29:17.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.670476  3896 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.144.1:36745 every 8 connection(s)
18:29:17.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.672034  3769 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/data/info.pb
18:29:17.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.675760  3648 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu as pid 3769
18:29:17.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.676024  3648 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal/instance
18:29:17.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:17 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535357, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.144.1@KRBTEST.COM for kudu/127.3.144.62@KRBTEST.COM
18:29:17.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.144.2@KRBTEST.COM; defaulting to no policy
18:29:17.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.695815  3897 heartbeater.cc:344] Connected to a master server at 127.3.144.62:33203
18:29:17.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.696286  3897 heartbeater.cc:461] Registering TS with master...
18:29:17.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.697510  3897 heartbeater.cc:507] Master 127.3.144.62:33203 requested a full tablet report, sending...
18:29:17.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.144.2@KRBTEST.COM" created.
18:29:17.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.700423  3706 ts_manager.cc:194] Registered new tserver with Master: 84e94e29ec9d44728804b9830a754746 (127.3.144.1:36745)
18:29:17.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.702322  3706 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.3.144.1@KRBTEST.COM'} at 127.3.144.1:46699
18:29:17.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.144.2@KRBTEST.COM; defaulting to no policy
18:29:17.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.144.2@KRBTEST.COM" created.
18:29:17.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:17.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:17.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:17.769520  3648 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:17.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/logs
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data/info.pb
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:29:17.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.2:0
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.144.2
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.2
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:17.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.144.62:33203
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.2
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:17.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:17.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:17.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
18:29:17.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
18:29:17.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
18:29:18.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.129240  3905 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
18:29:18.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.129601  3905 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
18:29:18.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.129648  3905 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:29:18.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.129745  3905 flags.cc:432] Enabled unsafe flag: --never_fsync=true
18:29:18.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.138386  3905 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
18:29:18.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.138610  3905 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.3.144.2
18:29:18.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.150046  3905 tablet_server_runner.cc:78] Tablet server non-default flags:
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.2
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.2:0
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data/info.pb
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.2
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.144.62:33203
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.144.2
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/logs
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 3ddc7d4404ac90c834b1cdd020973c286611e9b0
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
18:29:18.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 18:01:24 UTC on bdcb31816ec0
18:29:18.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16870
18:29:18.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
18:29:18.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.151871  3905 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:29:18.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.153309  3905 file_cache.cc:492] Constructed file cache file cache with capacity 419430
18:29:18.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.166792  3911 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
18:29:18.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.167708  3910 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
18:29:18.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.168331  3913 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
18:29:18.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.170184  3905 server_base.cc:1061] running on GCE node
18:29:18.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.171069  3905 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:29:18.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.172287  3905 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:29:18.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.173607  3905 hybrid_clock.cc:648] HybridClock initialized: now 1783535358173522 us; error 122 us; skew 500 ppm
18:29:18.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.174125  3905 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
18:29:18.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:18 dist-test-slave-9gpt krb5kdc[3658](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535358, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.144.2@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
18:29:18.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.178141  3905 init.cc:377] Logged in from keytab as kudu/127.3.144.2@KRBTEST.COM (short username kudu)
18:29:18.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.179957  3905 webserver.cc:534] Webserver started at http://127.3.144.2:46841/ using document root <none> and password file <none>
18:29:18.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.180763  3905 fs_manager.cc:362] Metadata directory not provided
18:29:18.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.180855  3905 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:29:18.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.181180  3905 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
18:29:18.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.183802  3905 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data/instance:
18:29:18.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d8ec9c8ee8a547b69a6e16714717d9d5"
18:29:18.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:18 on dist-test-slave-9gpt"
18:29:18.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.184733  3905 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal/instance:
18:29:18.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d8ec9c8ee8a547b69a6e16714717d9d5"
18:29:18.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:18 on dist-test-slave-9gpt"
18:29:18.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.190863  3905 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.007s	sys 0.000s
18:29:18.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.195725  3920 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:29:18.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.198108  3905 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.006s	sys 0.000s
18:29:18.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.198374  3905 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data,/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d8ec9c8ee8a547b69a6e16714717d9d5"
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:18 on dist-test-slave-9gpt"
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.198678  3905 fs_report.cc:389] FS layout report
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data/data
18:29:18.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:29:18.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:29:18.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.227344  3905 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
18:29:18.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.231894  3905 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:29:18.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.232234  3905 kserver.cc:163] Server-wide thread pool size limit: 3276
18:29:18.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.233434  3905 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
18:29:18.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.235213  3905 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
18:29:18.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.235359  3905 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
18:29:18.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.235471  3905 ts_tablet_manager.cc:616] Registered 0 tablets
18:29:18.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.235558  3905 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
18:29:18.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.274832  3905 rpc_server.cc:307] RPC server started. Bound to: 127.3.144.2:42333
18:29:18.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.274924  4032 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.144.2:42333 every 8 connection(s)
18:29:18.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.276618  3905 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/data/info.pb
18:29:18.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.282164  3648 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu as pid 3905
18:29:18.282 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.282379  3648 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal/instance
18:29:18.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:18 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535358, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.144.2@KRBTEST.COM for kudu/127.3.144.62@KRBTEST.COM
18:29:18.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.144.3@KRBTEST.COM; defaulting to no policy
18:29:18.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.302969  4033 heartbeater.cc:344] Connected to a master server at 127.3.144.62:33203
18:29:18.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.303335  4033 heartbeater.cc:461] Registering TS with master...
18:29:18.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.304594  4033 heartbeater.cc:507] Master 127.3.144.62:33203 requested a full tablet report, sending...
18:29:18.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:18.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.144.3@KRBTEST.COM" created.
18:29:18.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.306998  3706 ts_manager.cc:194] Registered new tserver with Master: d8ec9c8ee8a547b69a6e16714717d9d5 (127.3.144.2:42333)
18:29:18.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.308137  3706 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.3.144.2@KRBTEST.COM'} at 127.3.144.2:56129
18:29:18.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:18.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:18.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.144.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:18.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.144.3@KRBTEST.COM; defaulting to no policy
18:29:18.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:18.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.144.3@KRBTEST.COM" created.
18:29:18.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
18:29:18.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:18.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.144.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab.
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.371084  3648 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:18.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/logs
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data/info.pb
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.3:0
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.144.3
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.3
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.144.62:33203
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.3
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
18:29:18.372 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
18:29:18.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
18:29:18.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.705912  3897 heartbeater.cc:499] Master 127.3.144.62:33203 was elected leader, sending a full tablet report...
18:29:18.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.729897  4041 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
18:29:18.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.730237  4041 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
18:29:18.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.730285  4041 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
18:29:18.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.730386  4041 flags.cc:432] Enabled unsafe flag: --never_fsync=true
18:29:18.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.738955  4041 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
18:29:18.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.739189  4041 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.3.144.3
18:29:18.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.754912  4041 tablet_server_runner.cc:78] Tablet server non-default flags:
18:29:18.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
18:29:18.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.144.20:36775
18:29:18.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
18:29:18.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/kudu.keytab
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.144.3
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.144.3:0
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
18:29:18.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
18:29:18.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
18:29:18.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
18:29:18.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
18:29:18.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data/info.pb
18:29:18.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.144.3
18:29:18.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.144.62:33203
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.144.3
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/logs
18:29:18.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 3ddc7d4404ac90c834b1cdd020973c286611e9b0
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 18:01:24 UTC on bdcb31816ec0
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16870
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
18:29:18.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.757306  4041 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
18:29:18.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.759420  4041 file_cache.cc:492] Constructed file cache file cache with capacity 419430
18:29:18.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.771216  4046 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
18:29:18.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.771992  4047 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
18:29:18.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:18.775396  4049 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
18:29:18.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.775518  4041 server_base.cc:1061] running on GCE node
18:29:18.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.776618  4041 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
18:29:18.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.778182  4041 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
18:29:18.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.779515  4041 hybrid_clock.cc:648] HybridClock initialized: now 1783535358779344 us; error 119 us; skew 500 ppm
18:29:18.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.780051  4041 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
18:29:18.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:18 dist-test-slave-9gpt krb5kdc[3658](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535358, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.144.3@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
18:29:18.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.786070  4041 init.cc:377] Logged in from keytab as kudu/127.3.144.3@KRBTEST.COM (short username kudu)
18:29:18.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.788507  4041 webserver.cc:534] Webserver started at http://127.3.144.3:37061/ using document root <none> and password file <none>
18:29:18.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.789839  4041 fs_manager.cc:362] Metadata directory not provided
18:29:18.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.790015  4041 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
18:29:18.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.790624  4041 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
18:29:18.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.795054  4041 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data/instance:
18:29:18.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9ed9961987bc472cac8ac4cb7ac90fd9"
18:29:18.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:18 on dist-test-slave-9gpt"
18:29:18.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.796649  4041 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal/instance:
18:29:18.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9ed9961987bc472cac8ac4cb7ac90fd9"
18:29:18.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:18 on dist-test-slave-9gpt"
18:29:18.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.806727  4041 fs_manager.cc:696] Time spent creating directory manager: real 0.009s	user 0.011s	sys 0.000s
18:29:18.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.813006  4056 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
18:29:18.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.815287  4041 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.003s	sys 0.000s
18:29:18.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.815544  4041 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data,/tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal
18:29:18.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9ed9961987bc472cac8ac4cb7ac90fd9"
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 18:29:18 on dist-test-slave-9gpt"
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.815848  4041 fs_report.cc:389] FS layout report
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data/data
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
18:29:18.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
18:29:18.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
18:29:18.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.867729  4041 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
18:29:18.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.874687  4041 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
18:29:18.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.875072  4041 kserver.cc:163] Server-wide thread pool size limit: 3276
18:29:18.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.876591  4041 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
18:29:18.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.879199  4041 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
18:29:18.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.879345  4041 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
18:29:18.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.879485  4041 ts_tablet_manager.cc:616] Registered 0 tablets
18:29:18.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.879571  4041 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
18:29:18.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.930974  4041 rpc_server.cc:307] RPC server started. Bound to: 127.3.144.3:33165
18:29:18.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.930991  4168 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.144.3:33165 every 8 connection(s)
18:29:18.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.933465  4041 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/data/info.pb
18:29:18.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.934125  3648 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu as pid 4041
18:29:18.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.934384  3648 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal/instance
18:29:18.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:18 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535358, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.144.3@KRBTEST.COM for kudu/127.3.144.62@KRBTEST.COM
18:29:18.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.964170  4169 heartbeater.cc:344] Connected to a master server at 127.3.144.62:33203
18:29:18.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.964648  4169 heartbeater.cc:461] Registering TS with master...
18:29:18.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.965947  4169 heartbeater.cc:507] Master 127.3.144.62:33203 requested a full tablet report, sending...
18:29:18.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.967845  3706 ts_manager.cc:194] Registered new tserver with Master: 9ed9961987bc472cac8ac4cb7ac90fd9 (127.3.144.3:33165)
18:29:18.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.968833  3706 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.3.144.3@KRBTEST.COM'} at 127.3.144.3:33263
18:29:18.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:18.980548  3648 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
18:29:18.984 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
18:29:18.986 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

18:29:18.997 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

18:29:19.007 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.3.144.62"
      port: 33203
    }
    bound_http_address {
      host: "127.3.144.62"
      port: 36273
    }
  }
}

18:29:19.011 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

18:29:19.013 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.3.144.1"
      port: 36745
    }
    bound_http_address {
      host: "127.3.144.1"
      port: 34975
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.3.144.2"
      port: 42333
    }
    bound_http_address {
      host: "127.3.144.2"
      port: 46841
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.3.144.3"
      port: 33165
    }
    bound_http_address {
      host: "127.3.144.3"
      port: 37061
    }
  }
}

18:29:19.014 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
18:29:19.222 [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)
18:29:19.249 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/krb5kdc/krb5cc
18:29:19.301 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
18:29:19.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:19.311300  4033 heartbeater.cc:499] Master 127.3.144.62:33203 was elected leader, sending a full tablet report...
18:29:19.434 [DEBUG - main] (Connection.java:748) [peer master-127.3.144.62:33203(127.3.144.62:33203)] connecting to peer
18:29:19.518 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.3.144.62:33203(127.3.144.62:33203)] Successfully connected to peer
18:29:19.619 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.3.144.62
18:29:19.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:19 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (1 etypes {17}) 127.0.0.1: ISSUE: authtime 1783535356, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.3.144.62@KRBTEST.COM
18:29:19.909 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x1075fd32, L:/127.0.0.1:58186 - R:/127.3.144.62:33203] using SASL/GSSAPI
18:29:19.949 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
18:29:19.950 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=564), Traces: [0ms] refreshing cache from master, [30ms] Sub RPC ConnectToMaster: sending RPC to server master-127.3.144.62:33203, [536ms] Sub RPC ConnectToMaster: received response from server master-127.3.144.62:33203: OK, deferred=null) after lookup
18:29:19.972 [DEBUG - main] (Connection.java:575) [peer master-127.3.144.62:33203(127.3.144.62:33203)] disconnecting while in state READY
18:29:19.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:19.972144  4169 heartbeater.cc:499] Master 127.3.144.62:33203 was elected leader, sending a full tablet report...
18:29:19.979 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.3.144.62:33203(127.3.144.62:33203)] handling channelInactive
18:29:19.980 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.3.144.62:33203(127.3.144.62:33203)] cleaning up while in state READY due to: connection closed
18:29:19.980 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.3.144.62:33203(127.3.144.62:33203) for tablet Kudu Master: connection closed
18:29:19.988 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.3.144.62:33203(127.3.144.62:33203)] connecting to peer
18:29:19.991 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.3.144.62:33203(127.3.144.62:33203)] Successfully connected to peer
18:29:20.055 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x400c1d3c, L:/127.0.0.1:58194 - R:/127.3.144.62:33203] using TOKEN/null
18:29:20.059 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
18:29:20.059 [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.3.144.62:33203, [7ms] received response from server master-127.3.144.62:33203: Network error: connection closed, [8ms] delaying RPC due to: Network error: connection closed, [14ms] refreshing cache from master, [15ms] Sub RPC ConnectToMaster: sending RPC to server master-127.3.144.62:33203, [85ms] Sub RPC ConnectToMaster: received response from server master-127.3.144.62:33203: OK, deferred=Deferred@1862599194(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.130291  3706 catalog_manager.cc:2282] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:58194:
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
18:29:20.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:29:20.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
18:29:20.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:29:20.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
18:29:20.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:20.138096  3706 catalog_manager.cc:7076] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table TestAuthnTokenReacquireOpen-table in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
18:29:20.175 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 0226d623fcb64d7b9ed32d29f3f0db92
18:29:20.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.219769  3832 tablet_service.cc:1511] Processing CreateTablet for tablet 7c8aa8f5bf6e46759252ec164bdc73e7 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=0226d623fcb64d7b9ed32d29f3f0db92]), partition=RANGE (key) PARTITION UNBOUNDED
18:29:20.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.221619  3832 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 7c8aa8f5bf6e46759252ec164bdc73e7. 1 dirs total, 0 dirs full, 0 dirs failed
18:29:20.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.224267  3968 tablet_service.cc:1511] Processing CreateTablet for tablet 7c8aa8f5bf6e46759252ec164bdc73e7 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=0226d623fcb64d7b9ed32d29f3f0db92]), partition=RANGE (key) PARTITION UNBOUNDED
18:29:20.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.225992  3968 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 7c8aa8f5bf6e46759252ec164bdc73e7. 1 dirs total, 0 dirs full, 0 dirs failed
18:29:20.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.233422  4104 tablet_service.cc:1511] Processing CreateTablet for tablet 7c8aa8f5bf6e46759252ec164bdc73e7 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=0226d623fcb64d7b9ed32d29f3f0db92]), partition=RANGE (key) PARTITION UNBOUNDED
18:29:20.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.237700  4104 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 7c8aa8f5bf6e46759252ec164bdc73e7. 1 dirs total, 0 dirs full, 0 dirs failed
18:29:20.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.244663  4188 tablet_bootstrap.cc:492] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Bootstrap starting.
18:29:20.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.252393  4188 tablet_bootstrap.cc:654] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Neither blocks nor log segments found. Creating new log.
18:29:20.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.254850  4188 log.cc:826] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Log is configured to *not* fsync() on all Append() calls
18:29:20.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.255551  4189 tablet_bootstrap.cc:492] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Bootstrap starting.
18:29:20.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.262784  4189 tablet_bootstrap.cc:654] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Neither blocks nor log segments found. Creating new log.
18:29:20.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.264737  4189 log.cc:826] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Log is configured to *not* fsync() on all Append() calls
18:29:20.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.265324  4188 tablet_bootstrap.cc:492] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: No bootstrap required, opened a new log
18:29:20.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.266010  4188 ts_tablet_manager.cc:1403] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Time spent bootstrapping tablet: real 0.022s	user 0.017s	sys 0.000s
18:29:20.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.271095  4191 tablet_bootstrap.cc:492] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Bootstrap starting.
18:29:20.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.278534  4188 raft_consensus.cc:359] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.279429  4188 raft_consensus.cc:385] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:29:20.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.279789  4191 tablet_bootstrap.cc:654] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Neither blocks nor log segments found. Creating new log.
18:29:20.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.279814  4188 raft_consensus.cc:740] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 84e94e29ec9d44728804b9830a754746, State: Initialized, Role: FOLLOWER
18:29:20.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.280717  4188 consensus_queue.cc:260] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [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: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.281351  4191 log.cc:826] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Log is configured to *not* fsync() on all Append() calls
18:29:20.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.289538  4188 ts_tablet_manager.cc:1434] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Time spent starting tablet: real 0.023s	user 0.012s	sys 0.007s
18:29:20.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.291203  4191 tablet_bootstrap.cc:492] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: No bootstrap required, opened a new log
18:29:20.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.291765  4189 tablet_bootstrap.cc:492] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: No bootstrap required, opened a new log
18:29:20.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.291886  4191 ts_tablet_manager.cc:1403] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Time spent bootstrapping tablet: real 0.021s	user 0.011s	sys 0.005s
18:29:20.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.295413  4189 ts_tablet_manager.cc:1403] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Time spent bootstrapping tablet: real 0.040s	user 0.009s	sys 0.012s
18:29:20.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.298259  4191 raft_consensus.cc:359] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.298730  4191 raft_consensus.cc:385] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:29:20.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.298838  4191 raft_consensus.cc:740] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 9ed9961987bc472cac8ac4cb7ac90fd9, State: Initialized, Role: FOLLOWER
18:29:20.300 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.299779  4191 consensus_queue.cc:260] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [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: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.301743  4191 ts_tablet_manager.cc:1434] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Time spent starting tablet: real 0.010s	user 0.009s	sys 0.000s
18:29:20.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.306790  4189 raft_consensus.cc:359] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.307344  4189 raft_consensus.cc:385] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
18:29:20.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.307456  4189 raft_consensus.cc:740] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d8ec9c8ee8a547b69a6e16714717d9d5, State: Initialized, Role: FOLLOWER
18:29:20.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.308323  4189 consensus_queue.cc:260] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [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: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.311328  4189 ts_tablet_manager.cc:1434] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Time spent starting tablet: real 0.015s	user 0.004s	sys 0.007s
18:29:20.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:20.426040  3898 tablet.cc:2406] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Can't schedule compaction. Clean time has not been advanced past its initial value.
18:29:20.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:20.440213  4170 tablet.cc:2406] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Can't schedule compaction. Clean time has not been advanced past its initial value.
18:29:20.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:20.537375  4034 tablet.cc:2406] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Can't schedule compaction. Clean time has not been advanced past its initial value.
18:29:20.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.600788  4196 raft_consensus.cc:493] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
18:29:20.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.601064  4196 raft_consensus.cc:515] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.603634  4196 leader_election.cc:290] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 9ed9961987bc472cac8ac4cb7ac90fd9 (127.3.144.3:33165), 84e94e29ec9d44728804b9830a754746 (127.3.144.1:36745)
18:29:20.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.611428  3852 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "7c8aa8f5bf6e46759252ec164bdc73e7" candidate_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "84e94e29ec9d44728804b9830a754746" is_pre_election: true
18:29:20.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.611997  3852 raft_consensus.cc:2468] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate d8ec9c8ee8a547b69a6e16714717d9d5 in term 0.
18:29:20.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.612073  4124 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "7c8aa8f5bf6e46759252ec164bdc73e7" candidate_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" is_pre_election: true
18:29:20.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.612528  4124 raft_consensus.cc:2468] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate d8ec9c8ee8a547b69a6e16714717d9d5 in term 0.
18:29:20.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.612876  3923 leader_election.cc:304] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [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: 84e94e29ec9d44728804b9830a754746, d8ec9c8ee8a547b69a6e16714717d9d5; no voters: 
18:29:20.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.613329  4196 raft_consensus.cc:2804] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Leader pre-election won for term 1
18:29:20.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.613708  4196 raft_consensus.cc:493] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
18:29:20.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.613911  4196 raft_consensus.cc:3060] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 0 FOLLOWER]: Advancing to term 1
18:29:20.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.616155  4196 raft_consensus.cc:515] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.616992  4196 leader_election.cc:290] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [CANDIDATE]: Term 1 election: Requested vote from peers 9ed9961987bc472cac8ac4cb7ac90fd9 (127.3.144.3:33165), 84e94e29ec9d44728804b9830a754746 (127.3.144.1:36745)
18:29:20.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.617625  3852 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "7c8aa8f5bf6e46759252ec164bdc73e7" candidate_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "84e94e29ec9d44728804b9830a754746"
18:29:20.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.617640  4124 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "7c8aa8f5bf6e46759252ec164bdc73e7" candidate_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9"
18:29:20.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.617887  4124 raft_consensus.cc:3060] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 0 FOLLOWER]: Advancing to term 1
18:29:20.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.617950  3852 raft_consensus.cc:3060] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 0 FOLLOWER]: Advancing to term 1
18:29:20.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.620230  4124 raft_consensus.cc:2468] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate d8ec9c8ee8a547b69a6e16714717d9d5 in term 1.
18:29:20.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.620616  3852 raft_consensus.cc:2468] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate d8ec9c8ee8a547b69a6e16714717d9d5 in term 1.
18:29:20.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.620826  3922 leader_election.cc:304] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [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: 9ed9961987bc472cac8ac4cb7ac90fd9, d8ec9c8ee8a547b69a6e16714717d9d5; no voters: 
18:29:20.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.621219  4196 raft_consensus.cc:2804] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 1 FOLLOWER]: Leader election won for term 1
18:29:20.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.621943  4196 raft_consensus.cc:697] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 1 LEADER]: Becoming Leader. State: Replica: d8ec9c8ee8a547b69a6e16714717d9d5, State: Running, Role: LEADER
18:29:20.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.622514  4196 consensus_queue.cc:237] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [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: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } }
18:29:20.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:20.628541  3706 catalog_manager.cc:5696] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 reported cstate change: term changed from 0 to 1, leader changed from <none> to d8ec9c8ee8a547b69a6e16714717d9d5 (127.3.144.2). New cstate: current_term: 1 leader_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "d8ec9c8ee8a547b69a6e16714717d9d5" member_type: VOTER last_known_addr { host: "127.3.144.2" port: 42333 } health_report { overall_health: HEALTHY } } }
18:29:20.714 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 0226d623fcb64d7b9ed32d29f3f0db92
18:29:21.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.075471  4196 consensus_queue.cc:1048] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [LEADER]: Connected to new peer: Peer: permanent_uuid: "84e94e29ec9d44728804b9830a754746" member_type: VOTER last_known_addr { host: "127.3.144.1" port: 36745 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
18:29:21.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.084890  4204 consensus_queue.cc:1048] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [LEADER]: Connected to new peer: Peer: permanent_uuid: "9ed9961987bc472cac8ac4cb7ac90fd9" member_type: VOTER last_known_addr { host: "127.3.144.3" port: 33165 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
18:29:21.720 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 0226d623fcb64d7b9ed32d29f3f0db92
18:29:21.742 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 7c8aa8f5bf6e46759252ec164bdc73e7 for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
18:29:21.748 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=7c8aa8f5bf6e46759252ec164bdc73e7@[84e94e29ec9d44728804b9830a754746(127.3.144.1:36745),9ed9961987bc472cac8ac4cb7ac90fd9(127.3.144.3:33165),d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)[L]]}]
18:29:21.756 [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=15), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.3.144.62:33203, [8ms] Sub RPC GetTableLocations: received response from server master-127.3.144.62:33203: OK, deferred=null)) after lookup
18:29:21.768 [DEBUG - kudu-nio-1] (Connection.java:748) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] connecting to peer
18:29:21.771 [DEBUG - kudu-nio-2] (Connection.java:757) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] Successfully connected to peer
18:29:21.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:21.821120  4212 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:39970: token expired
18:29:21.826 [DEBUG - kudu-nio-2] (Negotiator.java:352) peer /127.3.144.2:42333 sent connection negotiation error: Not authorized: token expired
18:29:21.828 [DEBUG - kudu-nio-2] (Connection.java:239) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] handling channelInactive
18:29:21.828 [DEBUG - kudu-nio-2] (Connection.java:709) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
18:29:21.830 [DEBUG - kudu-nio-2] (Connection.java:748) [peer master-127.3.144.62:33203(127.3.144.62:33203)] connecting to peer
18:29:21.833 [DEBUG - kudu-nio-3] (Connection.java:757) [peer master-127.3.144.62:33203(127.3.144.62:33203)] Successfully connected to peer
18:29:21.840 [DEBUG - kudu-nio-3] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.3.144.62
18:29:21.868 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0xcdfc2440, L:/127.0.0.1:58198 - R:/127.3.144.62:33203] using SASL/GSSAPI
18:29:21.871 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
18:29:21.872 [DEBUG - kudu-nio-3] (Connection.java:748) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] connecting to peer
18:29:21.874 [DEBUG - kudu-nio-4] (Connection.java:757) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] Successfully connected to peer
18:29:21.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:21.894272  4212 fault_injection.cc:43] FAULT INJECTION ENABLED!
18:29:21.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:21.894820  4212 fault_injection.cc:44] THIS SERVER MAY CRASH!
18:29:21.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 18:29:21.895699  4212 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:39974: token signing key expired
18:29:21.896 [DEBUG - kudu-nio-4] (Negotiator.java:352) peer /127.3.144.2:42333 sent connection negotiation error: Not authorized: token signing key expired
18:29:21.897 [DEBUG - kudu-nio-4] (Connection.java:239) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] handling channelInactive
18:29:21.897 [DEBUG - kudu-nio-4] (Connection.java:709) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
18:29:21.901 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
18:29:21.902 [DEBUG - kudu-nio-3] (Connection.java:748) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] connecting to peer
18:29:21.904 [DEBUG - kudu-nio-5] (Connection.java:757) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] Successfully connected to peer
18:29:21.944 [DEBUG - kudu-nio-5] (Negotiator.java:919) Authenticated connection [id: 0xe28f8ddd, L:/127.0.0.1:39976 - R:/127.3.144.2:42333] using TOKEN/null
18:29:21.969 [DEBUG - kudu-nio-5] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=7c8aa8f5bf6e46759252ec164bdc73e7, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
18:29:21.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.982318  3705 catalog_manager.cc:2532] Servicing SoftDeleteTable request from {username='test-admin'} at 127.0.0.1:58194:
18:29:21.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
18:29:21.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.982614  3705 catalog_manager.cc:2780] Servicing DeleteTable request from {username='test-admin'} at 127.0.0.1:58194:
18:29:21.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
18:29:21.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.993953  3705 catalog_manager.cc:5983] T 00000000000000000000000000000000 P c06f6dce11544c01a05d672d9ca97178: Sending DeleteTablet for 3 replicas of tablet 7c8aa8f5bf6e46759252ec164bdc73e7
18:29:21.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.996345  4104 tablet_service.cc:1558] Processing DeleteTablet for tablet 7c8aa8f5bf6e46759252ec164bdc73e7 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 18:29:21 UTC) from {username='kudu', principal='kudu/127.3.144.62@KRBTEST.COM'} at 127.0.0.1:38828
18:29:21.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.996557  3968 tablet_service.cc:1558] Processing DeleteTablet for tablet 7c8aa8f5bf6e46759252ec164bdc73e7 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 18:29:21 UTC) from {username='kudu', principal='kudu/127.3.144.62@KRBTEST.COM'} at 127.0.0.1:39956
18:29:21.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:21.997303  3832 tablet_service.cc:1558] Processing DeleteTablet for tablet 7c8aa8f5bf6e46759252ec164bdc73e7 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 18:29:21 UTC) from {username='kudu', principal='kudu/127.3.144.62@KRBTEST.COM'} at 127.0.0.1:38898
18:29:22.013 [DEBUG - main] (Connection.java:575) [peer master-127.3.144.62:33203(127.3.144.62:33203)] disconnecting while in state READY
18:29:22.014 [DEBUG - main] (Connection.java:575) [peer master-127.3.144.62:33203(127.3.144.62:33203)] disconnecting while in state READY
18:29:22.014 [DEBUG - main] (Connection.java:575) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] disconnecting while in state READY
18:29:22.015 [DEBUG - kudu-nio-3] (Connection.java:239) [peer master-127.3.144.62:33203(127.3.144.62:33203)] handling channelInactive
18:29:22.016 [DEBUG - kudu-nio-5] (Connection.java:239) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] handling channelInactive
18:29:22.016 [DEBUG - kudu-nio-3] (Connection.java:709) [peer master-127.3.144.62:33203(127.3.144.62:33203)] cleaning up while in state READY due to: connection closed
18:29:22.016 [DEBUG - kudu-nio-5] (Connection.java:709) [peer d8ec9c8ee8a547b69a6e16714717d9d5(127.3.144.2:42333)] cleaning up while in state READY due to: connection closed
18:29:22.022 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2704) Releasing all remaining resources
18:29:22.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.026001  4224 tablet_replica.cc:333] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: stopping tablet replica
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.026602  4224 raft_consensus.cc:2243] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 1 FOLLOWER]: Raft consensus shutting down.
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.027046  4224 raft_consensus.cc:2272] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746 [term 1 FOLLOWER]: Raft consensus is shut down!
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.027935  4223 tablet_replica.cc:333] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: stopping tablet replica
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.028401  4223 raft_consensus.cc:2243] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 1 LEADER]: Raft consensus shutting down.
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.029409  4222 tablet_replica.cc:333] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: stopping tablet replica
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.029640  4223 raft_consensus.cc:2272] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5 [term 1 FOLLOWER]: Raft consensus is shut down!
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.031564  4223 ts_tablet_manager.cc:1916] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Deleting tablet data with delete state TABLET_DATA_DELETED
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.033613  4222 raft_consensus.cc:2243] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 1 FOLLOWER]: Raft consensus shutting down.
18:29:22.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.034147  4222 raft_consensus.cc:2272] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9 [term 1 FOLLOWER]: Raft consensus is shut down!
18:29:22.038 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.3.144.62:33203(127.3.144.62:33203)] handling channelInactive
18:29:22.039 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.3.144.62:33203(127.3.144.62:33203)] cleaning up while in state READY due to: connection closed
18:29:22.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.039772  4224 ts_tablet_manager.cc:1916] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Deleting tablet data with delete state TABLET_DATA_DELETED
18:29:22.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.040452  4223 ts_tablet_manager.cc:1929] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
18:29:22.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.040969  4223 log.cc:1199] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Deleting WAL directory at /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-1/wal/wals/7c8aa8f5bf6e46759252ec164bdc73e7
18:29:22.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.041710  4222 ts_tablet_manager.cc:1916] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Deleting tablet data with delete state TABLET_DATA_DELETED
18:29:22.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.041818  4223 ts_tablet_manager.cc:1950] T 7c8aa8f5bf6e46759252ec164bdc73e7 P d8ec9c8ee8a547b69a6e16714717d9d5: Deleting consensus metadata
18:29:22.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.042214  3648 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu pid 3769
18:29:22.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.044011  3693 catalog_manager.cc:5027] TS d8ec9c8ee8a547b69a6e16714717d9d5 (127.3.144.2:42333): tablet 7c8aa8f5bf6e46759252ec164bdc73e7 (table TestAuthnTokenReacquireOpen-table [id=0226d623fcb64d7b9ed32d29f3f0db92]) successfully deleted
18:29:22.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.049985  4224 ts_tablet_manager.cc:1929] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
18:29:22.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.050493  4224 log.cc:1199] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Deleting WAL directory at /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-0/wal/wals/7c8aa8f5bf6e46759252ec164bdc73e7
18:29:22.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.051076  4222 ts_tablet_manager.cc:1929] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
18:29:22.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.051568  4222 log.cc:1199] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Deleting WAL directory at /tmp/dist-test-taskrqG6ze/test-tmp/mini-kudu-cluster6448540568733181208/ts-2/wal/wals/7c8aa8f5bf6e46759252ec164bdc73e7
18:29:22.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.052356  4222 ts_tablet_manager.cc:1950] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 9ed9961987bc472cac8ac4cb7ac90fd9: Deleting consensus metadata
18:29:22.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.053051  4224 ts_tablet_manager.cc:1950] T 7c8aa8f5bf6e46759252ec164bdc73e7 P 84e94e29ec9d44728804b9830a754746: Deleting consensus metadata
18:29:22.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.054715  3691 catalog_manager.cc:5027] TS 9ed9961987bc472cac8ac4cb7ac90fd9 (127.3.144.3:33165): tablet 7c8aa8f5bf6e46759252ec164bdc73e7 (table TestAuthnTokenReacquireOpen-table [id=0226d623fcb64d7b9ed32d29f3f0db92]) successfully deleted
18:29:22.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.055337  3692 catalog_manager.cc:5027] TS 84e94e29ec9d44728804b9830a754746 (127.3.144.1:36745): tablet 7c8aa8f5bf6e46759252ec164bdc73e7 (table TestAuthnTokenReacquireOpen-table [id=0226d623fcb64d7b9ed32d29f3f0db92]) successfully deleted
18:29:22.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:22 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535356, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.3.144.1@KRBTEST.COM
18:29:22.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.073307  3892 generic_service.cc:196] Checking for leaks (request via RPC)
18:29:22.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.200006  3648 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu with pid 3769
18:29:22.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.219316  3648 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu pid 3905
18:29:22.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:22 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535356, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.3.144.2@KRBTEST.COM
18:29:22.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.238296  4028 generic_service.cc:196] Checking for leaks (request via RPC)
18:29:22.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.356401  3648 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu with pid 3905
18:29:22.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.376277  3648 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu pid 4041
18:29:22.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 18:29:22 dist-test-slave-9gpt krb5kdc[3658](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783535356, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.3.144.3@KRBTEST.COM
18:29:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.394291  4164 generic_service.cc:196] Checking for leaks (request via RPC)
18:29:22.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.488432  3648 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu with pid 4041
18:29:22.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.509306  3648 external_mini_cluster.cc:1705] Attempting to check leaks for /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu pid 3674
18:29:22.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.510879  3736 generic_service.cc:196] Checking for leaks (request via RPC)
18:29:22.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 18:29:22.624228  3648 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskrqG6ze/build/asan/bin/kudu with pid 3674
18:29:22.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T18:29:22Z chronyd exiting

Time: 9.265

OK (1 test)

