JUnit version 4.13.2
.20:02:39.546 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1786478559536
20:02:39.587 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
20:02:39.673 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
20:02:39.685 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-tasko3XzJb/test-tmp
20:02:39.691 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/debug/bin
20:02:39.695 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-tasko3XzJb/java/build/dist-test/../../../build/debug/bin/kudu, test, mini_cluster, --serialization=pb]
20:02:39.953 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614"
  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 {
  }
}

20:02:39.971 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
20:02:39.973 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

20:02:39.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:39.975004 29126 mini_oidc.cc:150] Starting JWKS server
20:02:39.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:39.977603 29126 webserver.cc:533] Webserver started at http://127.0.0.1:41753/ using document root <none> and password file <none>
20:02:39.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:39.977807 29126 mini_oidc.cc:186] Starting OIDC Discovery server
20:02:39.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:39.978125 29126 webserver.cc:533] Webserver started at http://127.0.0.1:34747/ using document root <none> and password file <none>
20:02:40.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
20:02:40.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/principal' for realm 'KRBTEST.COM',
20:02:40.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
20:02:40.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:40 dist-test-slave-cbsf krb5kdc[29138](info): setting up network...
20:02:40.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
20:02:40.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:40 dist-test-slave-cbsf krb5kdc[29138](info): set up 2 sockets
20:02:40.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:40 dist-test-slave-cbsf krb5kdc[29138](info): commencing operation
20:02:40.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
20:02:42.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.224274 29126 mini_kdc.cc:121] Time spent starting KDC: real 2.238s	user 0.002s	sys 0.008s
20:02:42.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
20:02:42.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
20:02:42.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
20:02:42.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
20:02:42.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
20:02:42.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
20:02:42.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
20:02:42.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
20:02:42.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
20:02:42.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
20:02:42.317 [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-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/test-user.keytab.
20:02:42.318 [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-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/test-user.keytab.
20:02:42.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:42 dist-test-slave-cbsf krb5kdc[29138](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478562, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
20:02:42.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
20:02:42.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T20:02:42Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
20:02:42.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T20:02:42Z Disabled control of system clock
20:02:42.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.113.190@KRBTEST.COM; defaulting to no policy
20:02:42.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.113.190@KRBTEST.COM" created.
20:02:42.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.190 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.190 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.28.113.190@KRBTEST.COM; defaulting to no policy
20:02:42.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.28.113.190@KRBTEST.COM" created.
20:02:42.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.190 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.190 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.405233 29126 external_mini_cluster.cc:1372] Running /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:42.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:42.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/wal
20:02:42.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data
20:02:42.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:02:42.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:02:42.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:02:42.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:42.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:42.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:42.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/logs
20:02:42.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data/info.pb
20:02:42.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:42.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:02:42.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:02:42.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:02:42.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:02:42.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:42.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:42.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:42.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
20:02:42.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:02:42.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:02:42.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:02:42.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.190:35945
20:02:42.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.190
20:02:42.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:42.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:42.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.190
20:02:42.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:42.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
20:02:42.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
20:02:42.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
20:02:42.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:42.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:42.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:42.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:42.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:02:42.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.113.190:35945
20:02:42.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:02:42.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41753/jwks.json
20:02:42.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
20:02:42.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
20:02:42.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
20:02:42.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.540988 29158 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:02:42.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.541277 29158 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:02:42.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.541342 29158 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:02:42.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.545779 29158 flags.cc:432] Enabled experimental flag: --authn_token_validity_seconds=1
20:02:42.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.545869 29158 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
20:02:42.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.545900 29158 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:02:42.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.545922 29158 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
20:02:42.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.545943 29158 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
20:02:42.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.545964 29158 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
20:02:42.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.545980 29158 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:41753/jwks.json
20:02:42.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.551911 29158 master_runner.cc:387] Master server non-default flags:
20:02:42.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/logs
20:02:42.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:42.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:02:42.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:42.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:42.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:42.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:42.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data
20:02:42.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/wal
20:02:42.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
20:02:42.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:02:42.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.28.113.190:35945
20:02:42.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:42.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.190
20:02:42.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:42.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:42.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:02:42.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.190:35945
20:02:42.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:02:42.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:02:42.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:02:42.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:41753/jwks.json
20:02:42.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:42.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
20:02:42.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
20:02:42.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
20:02:42.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:42.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:42.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data/info.pb
20:02:42.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.190
20:02:42.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:42.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:02:42.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29158
20:02:42.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:42.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:02:42.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:02:42.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:42.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:02:42.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
20:02:42.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:02:42.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 12dab910780628d7c8d59e0ab03bf2fa661d310d
20:02:42.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:02:42.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 19:45:47 UTC on bdcb31816ec0
20:02:42.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 13292
20:02:42.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.553321 29158 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:02:42.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.554561 29158 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:02:42.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.562935 29163 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
20:02:42.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.562939 29167 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
20:02:42.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.564090 29164 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
20:02:42.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.565915 29158 server_base.cc:1061] running on GCE node
20:02:42.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.566741 29158 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:02:42.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.568243 29158 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:02:42.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.571599 29158 hybrid_clock.cc:648] HybridClock initialized: now 1786478562571503 us; error 133 us; skew 500 ppm
20:02:42.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:42 dist-test-slave-cbsf krb5kdc[29138](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478562, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.113.190@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
20:02:42.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.575412 29158 init.cc:377] Logged in from keytab as kudu/127.28.113.190@KRBTEST.COM (short username kudu)
20:02:42.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.576815 29158 webserver.cc:533] Webserver started at http://127.28.113.190:34325/ using document root <none> and password file <none>
20:02:42.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.577663 29158 fs_manager.cc:362] Metadata directory not provided
20:02:42.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.577750 29158 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:02:42.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.578047 29158 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:02:42.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.580003 29158 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data/instance:
20:02:42.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d0ec21115cce42b995ed80e7384a3289"
20:02:42.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:42 on dist-test-slave-cbsf"
20:02:42.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.580654 29158 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/wal/instance:
20:02:42.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d0ec21115cce42b995ed80e7384a3289"
20:02:42.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:42 on dist-test-slave-cbsf"
20:02:42.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.586807 29158 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.004s	sys 0.000s
20:02:42.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.591138 29177 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:02:42.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.592949 29158 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
20:02:42.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.593116 29158 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data,/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/wal
20:02:42.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d0ec21115cce42b995ed80e7384a3289"
20:02:42.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:42 on dist-test-slave-cbsf"
20:02:42.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.593216 29158 fs_report.cc:389] FS layout report
20:02:42.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:02:42.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/wal
20:02:42.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/wal
20:02:42.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data/data
20:02:42.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:02:42.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:02:42.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:02:42.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:02:42.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:02:42.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:02:42.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:02:42.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:02:42.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:02:42.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:02:42.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:02:42.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.629712 29158 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:02:42.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.634111 29158 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:02:42.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.634539 29158 kserver.cc:163] Server-wide thread pool size limit: 3276
20:02:42.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.643996 29240 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.113.190:35945 every 8 connection(s)
20:02:42.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.644006 29158 rpc_server.cc:307] RPC server started. Bound to: 127.28.113.190:35945
20:02:42.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.645447 29158 server_base.cc:1193] Dumped server information to /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/data/info.pb
20:02:42.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.646306 29126 external_mini_cluster.cc:1434] Started /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu as pid 29158
20:02:42.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.646477 29126 external_mini_cluster.cc:1448] Reading /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/master-0/wal/instance
20:02:42.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.649654 29241 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
20:02:42.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.657415 29241 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289: Bootstrap starting.
20:02:42.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.661464 29241 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289: Neither blocks nor log segments found. Creating new log.
20:02:42.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.662623 29241 log.cc:826] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289: Log is configured to *not* fsync() on all Append() calls
20:02:42.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.665643 29241 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289: No bootstrap required, opened a new log
20:02:42.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:42 dist-test-slave-cbsf krb5kdc[29138](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478562, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.28.113.190@KRBTEST.COM
20:02:42.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.670423 29241 raft_consensus.cc:359] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d0ec21115cce42b995ed80e7384a3289" member_type: VOTER last_known_addr { host: "127.28.113.190" port: 35945 } }
20:02:42.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.670889 29241 raft_consensus.cc:385] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:02:42.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.670977 29241 raft_consensus.cc:740] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d0ec21115cce42b995ed80e7384a3289, State: Initialized, Role: FOLLOWER
20:02:42.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.671937 29241 consensus_queue.cc:260] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [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: "d0ec21115cce42b995ed80e7384a3289" member_type: VOTER last_known_addr { host: "127.28.113.190" port: 35945 } }
20:02:42.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.672166 29241 raft_consensus.cc:399] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
20:02:42.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.672237 29241 raft_consensus.cc:493] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
20:02:42.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.672350 29241 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 0 FOLLOWER]: Advancing to term 1
20:02:42.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.674365 29241 raft_consensus.cc:515] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d0ec21115cce42b995ed80e7384a3289" member_type: VOTER last_known_addr { host: "127.28.113.190" port: 35945 } }
20:02:42.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.674902 29241 leader_election.cc:304] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [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: d0ec21115cce42b995ed80e7384a3289; no voters: 
20:02:42.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.675361 29241 leader_election.cc:290] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [CANDIDATE]: Term 1 election: Requested vote from peers 
20:02:42.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.678018 29241 sys_catalog.cc:565] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [sys.catalog]: configured and running, proceeding with master startup.
20:02:42.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.675938 29247 raft_consensus.cc:2804] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 1 FOLLOWER]: Leader election won for term 1
20:02:42.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.679325 29247 raft_consensus.cc:697] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [term 1 LEADER]: Becoming Leader. State: Replica: d0ec21115cce42b995ed80e7384a3289, State: Running, Role: LEADER
20:02:42.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.680061 29247 consensus_queue.cc:237] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [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: "d0ec21115cce42b995ed80e7384a3289" member_type: VOTER last_known_addr { host: "127.28.113.190" port: 35945 } }
20:02:42.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.682130 29247 sys_catalog.cc:455] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [sys.catalog]: SysCatalogTable state changed. Reason: New leader d0ec21115cce42b995ed80e7384a3289. Latest consensus state: current_term: 1 leader_uuid: "d0ec21115cce42b995ed80e7384a3289" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d0ec21115cce42b995ed80e7384a3289" member_type: VOTER last_known_addr { host: "127.28.113.190" port: 35945 } } }
20:02:42.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.682595 29249 sys_catalog.cc:455] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d0ec21115cce42b995ed80e7384a3289" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d0ec21115cce42b995ed80e7384a3289" member_type: VOTER last_known_addr { host: "127.28.113.190" port: 35945 } } }
20:02:42.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.682761 29249 sys_catalog.cc:458] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [sys.catalog]: This master's current role is: LEADER
20:02:42.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.683243 29247 sys_catalog.cc:458] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289 [sys.catalog]: This master's current role is: LEADER
20:02:42.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.683531 29261 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
20:02:42.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.688495 29261 catalog_manager.cc:1520] Initializing Kudu cluster ID...
20:02:42.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.695343 29261 catalog_manager.cc:1383] Generated new cluster ID: 1c39ccdd65664a3794b3d969f7db4c33
20:02:42.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.695483 29261 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
20:02:42.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.714002 29261 catalog_manager.cc:1406] Generated new certificate authority record
20:02:42.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.715032 29261 catalog_manager.cc:1540] Loading token signing keys...
20:02:42.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.724642 29261 catalog_manager.cc:6092] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289: Generated new TSK 0
20:02:42.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.725397 29261 catalog_manager.cc:1550] Initializing in-progress tserver states...
20:02:42.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.113.129@KRBTEST.COM; defaulting to no policy
20:02:42.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.113.129@KRBTEST.COM" created.
20:02:42.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.129 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.129 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.28.113.129@KRBTEST.COM; defaulting to no policy
20:02:42.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.28.113.129@KRBTEST.COM" created.
20:02:42.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:42.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.129 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.129 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:42.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.797107 29126 external_mini_cluster.cc:1372] Running /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:42.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal
20:02:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data
20:02:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:02:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:02:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:02:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:42.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:42.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/logs
20:02:42.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data/info.pb
20:02:42.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:42.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:02:42.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:02:42.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:02:42.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:02:42.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:42.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:42.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:42.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:02:42.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:02:42.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.129:0
20:02:42.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.113.129
20:02:42.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.129
20:02:42.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:42.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.113.190:35945
20:02:42.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:42.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.129
20:02:42.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:42.802 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
20:02:42.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
20:02:42.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
20:02:42.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:42.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:42.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:42.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:42.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
20:02:42.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
20:02:42.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
20:02:42.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.941519 29273 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
20:02:42.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.941903 29273 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:02:42.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.941931 29273 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:02:42.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.942004 29273 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:02:42.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.947026 29273 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:02:42.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.947278 29273 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.113.129
20:02:42.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.952880 29273 tablet_server_runner.cc:78] Tablet server non-default flags:
20:02:42.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/logs
20:02:42.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
20:02:42.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.129
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.129:0
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
20:02:42.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data/info.pb
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.129
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.113.190:35945
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:02:42.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29273
20:02:42.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:42.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:02:42.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:02:42.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:42.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.113.129
20:02:42.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:02:42.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:02:42.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:02:42.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 12dab910780628d7c8d59e0ab03bf2fa661d310d
20:02:42.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:02:42.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 19:45:47 UTC on bdcb31816ec0
20:02:42.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 13292
20:02:42.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.954829 29273 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:02:42.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.956209 29273 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:02:42.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.965512 29279 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
20:02:42.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.965632 29278 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
20:02:42.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:42.965493 29281 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
20:02:42.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.966202 29273 server_base.cc:1061] running on GCE node
20:02:42.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.966619 29273 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:02:42.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.967214 29273 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:02:42.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.970556 29273 hybrid_clock.cc:648] HybridClock initialized: now 1786478562969971 us; error 581 us; skew 500 ppm
20:02:42.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:42 dist-test-slave-cbsf krb5kdc[29138](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478562, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.113.129@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
20:02:42.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.974715 29273 init.cc:377] Logged in from keytab as kudu/127.28.113.129@KRBTEST.COM (short username kudu)
20:02:42.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.976354 29273 webserver.cc:533] Webserver started at http://127.28.113.129:33523/ using document root <none> and password file <none>
20:02:42.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.977257 29273 fs_manager.cc:362] Metadata directory not provided
20:02:42.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.977365 29273 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:02:42.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.977658 29273 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:02:42.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.979779 29273 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data/instance:
20:02:42.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "85ab7f08b786412c9674da89711500db"
20:02:42.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:42 on dist-test-slave-cbsf"
20:02:42.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.980600 29273 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal/instance:
20:02:42.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "85ab7f08b786412c9674da89711500db"
20:02:42.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:42 on dist-test-slave-cbsf"
20:02:42.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.984997 29273 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.000s
20:02:42.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.988224 29290 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:02:42.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.989624 29273 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
20:02:42.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.989805 29273 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data,/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal
20:02:42.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "85ab7f08b786412c9674da89711500db"
20:02:42.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:42 on dist-test-slave-cbsf"
20:02:42.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:42.989929 29273 fs_report.cc:389] FS layout report
20:02:42.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:02:42.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal
20:02:42.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal
20:02:42.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data/data
20:02:42.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:02:42.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:02:42.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:02:42.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:02:42.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:02:42.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:02:42.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:02:42.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:02:42.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:02:42.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:02:42.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:02:43.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.030143 29273 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:02:43.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.034149 29273 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:02:43.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.034432 29273 kserver.cc:163] Server-wide thread pool size limit: 3276
20:02:43.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.035292 29273 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:02:43.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.036545 29273 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:02:43.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.036630 29273 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:02:43.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.036731 29273 ts_tablet_manager.cc:616] Registered 0 tablets
20:02:43.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.036757 29273 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:02:43.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.049456 29273 rpc_server.cc:307] RPC server started. Bound to: 127.28.113.129:38055
20:02:43.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.049494 29422 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.113.129:38055 every 8 connection(s)
20:02:43.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.050813 29273 server_base.cc:1193] Dumped server information to /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/data/info.pb
20:02:43.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.055816 29126 external_mini_cluster.cc:1434] Started /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu as pid 29273
20:02:43.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.056015 29126 external_mini_cluster.cc:1448] Reading /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal/instance
20:02:43.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:43 dist-test-slave-cbsf krb5kdc[29138](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478562, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.113.129@KRBTEST.COM for kudu/127.28.113.190@KRBTEST.COM
20:02:43.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.113.130@KRBTEST.COM; defaulting to no policy
20:02:43.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.113.130@KRBTEST.COM" created.
20:02:43.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.072736 29424 heartbeater.cc:344] Connected to a master server at 127.28.113.190:35945
20:02:43.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.073040 29424 heartbeater.cc:461] Registering TS with master...
20:02:43.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.073736 29424 heartbeater.cc:507] Master 127.28.113.190:35945 requested a full tablet report, sending...
20:02:43.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.075845 29197 ts_manager.cc:194] Registered new tserver with Master: 85ab7f08b786412c9674da89711500db (127.28.113.129:38055)
20:02:43.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.077761 29197 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.28.113.129@KRBTEST.COM'} at 127.28.113.129:34417
20:02:43.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.130 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.130 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.28.113.130@KRBTEST.COM; defaulting to no policy
20:02:43.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.28.113.130@KRBTEST.COM" created.
20:02:43.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.130 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.130 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.124226 29126 external_mini_cluster.cc:1372] Running /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:43.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/logs
20:02:43.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data/info.pb
20:02:43.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:43.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:02:43.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:02:43.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:02:43.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:02:43.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:43.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:43.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:43.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:02:43.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:02:43.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.130:0
20:02:43.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.113.130
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.130
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.113.190:35945
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.130
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:43.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:43.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:43.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
20:02:43.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
20:02:43.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
20:02:43.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.252175 29434 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
20:02:43.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.252458 29434 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:02:43.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.252480 29434 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:02:43.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.252535 29434 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:02:43.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.256384 29434 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:02:43.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.256547 29434 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.113.130
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.261696 29434 tablet_server_runner.cc:78] Tablet server non-default flags:
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/logs
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.130
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:43.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.130:0
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data/info.pb
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.130
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:43.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.113.190:35945
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29434
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.113.130
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 12dab910780628d7c8d59e0ab03bf2fa661d310d
20:02:43.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:02:43.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 19:45:47 UTC on bdcb31816ec0
20:02:43.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 13292
20:02:43.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.262899 29434 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:02:43.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.263844 29434 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:02:43.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.274046 29439 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
20:02:43.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.274029 29442 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
20:02:43.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.274049 29440 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
20:02:43.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.274421 29434 server_base.cc:1061] running on GCE node
20:02:43.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.275731 29434 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:02:43.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.276564 29434 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:02:43.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.277802 29434 hybrid_clock.cc:648] HybridClock initialized: now 1786478563277738 us; error 89 us; skew 500 ppm
20:02:43.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:43 dist-test-slave-cbsf krb5kdc[29138](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478563, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.113.130@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
20:02:43.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.281582 29434 init.cc:377] Logged in from keytab as kudu/127.28.113.130@KRBTEST.COM (short username kudu)
20:02:43.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.282845 29434 webserver.cc:533] Webserver started at http://127.28.113.130:46533/ using document root <none> and password file <none>
20:02:43.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.283453 29434 fs_manager.cc:362] Metadata directory not provided
20:02:43.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.283526 29434 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:02:43.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.283720 29434 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:02:43.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.285794 29434 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data/instance:
20:02:43.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d755b2f75ce44340b89d2f8ffc4161c4"
20:02:43.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:43 on dist-test-slave-cbsf"
20:02:43.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.286760 29434 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal/instance:
20:02:43.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d755b2f75ce44340b89d2f8ffc4161c4"
20:02:43.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:43 on dist-test-slave-cbsf"
20:02:43.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.292253 29434 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.006s	sys 0.000s
20:02:43.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.295452 29454 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:02:43.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.297062 29434 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
20:02:43.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.297235 29434 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data,/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal
20:02:43.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d755b2f75ce44340b89d2f8ffc4161c4"
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:43 on dist-test-slave-cbsf"
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.297336 29434 fs_report.cc:389] FS layout report
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data/data
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:02:43.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:02:43.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:02:43.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.311537 29434 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:02:43.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.315924 29434 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:02:43.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.316134 29434 kserver.cc:163] Server-wide thread pool size limit: 3276
20:02:43.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.316815 29434 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:02:43.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.317850 29434 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:02:43.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.317903 29434 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:02:43.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.317947 29434 ts_tablet_manager.cc:616] Registered 0 tablets
20:02:43.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.317962 29434 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:02:43.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.329991 29434 rpc_server.cc:307] RPC server started. Bound to: 127.28.113.130:36355
20:02:43.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.330084 29589 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.113.130:36355 every 8 connection(s)
20:02:43.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.331175 29434 server_base.cc:1193] Dumped server information to /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/data/info.pb
20:02:43.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.332490 29126 external_mini_cluster.cc:1434] Started /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu as pid 29434
20:02:43.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.332580 29126 external_mini_cluster.cc:1448] Reading /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal/instance
20:02:43.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:43 dist-test-slave-cbsf krb5kdc[29138](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478563, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.113.130@KRBTEST.COM for kudu/127.28.113.190@KRBTEST.COM
20:02:43.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.28.113.131@KRBTEST.COM; defaulting to no policy
20:02:43.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.28.113.131@KRBTEST.COM" created.
20:02:43.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.351971 29590 heartbeater.cc:344] Connected to a master server at 127.28.113.190:35945
20:02:43.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.352326 29590 heartbeater.cc:461] Registering TS with master...
20:02:43.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.353478 29590 heartbeater.cc:507] Master 127.28.113.190:35945 requested a full tablet report, sending...
20:02:43.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.355340 29197 ts_manager.cc:194] Registered new tserver with Master: d755b2f75ce44340b89d2f8ffc4161c4 (127.28.113.130:36355)
20:02:43.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.356244 29197 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.28.113.130@KRBTEST.COM'} at 127.28.113.130:43553
20:02:43.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.131 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.28.113.131 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.28.113.131@KRBTEST.COM; defaulting to no policy
20:02:43.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.28.113.131@KRBTEST.COM" created.
20:02:43.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
20:02:43.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.131 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.28.113.131 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab.
20:02:43.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.397398 29126 external_mini_cluster.cc:1372] Running /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:43.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu
20:02:43.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal
20:02:43.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data
20:02:43.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:02:43.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:02:43.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:02:43.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:43.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:43.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:43.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/logs
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data/info.pb
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:02:43.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.131:0
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.113.131
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.131
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.113.190:35945
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.131
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
20:02:43.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:43.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:43.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:43.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:43.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
20:02:43.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
20:02:43.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
20:02:43.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.544420 29597 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
20:02:43.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.544736 29597 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:02:43.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.544760 29597 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:02:43.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.544814 29597 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:02:43.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.549512 29597 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:02:43.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.549724 29597 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.28.113.131
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.556416 29597 tablet_server_runner.cc:78] Tablet server non-default flags:
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/logs
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.28.113.148:36637
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/kudu.keytab
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.28.113.131
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.28.113.131:0
20:02:43.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data/info.pb
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.28.113.131
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:02:43.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.28.113.190:35945
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.29597
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.28.113.131
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 12dab910780628d7c8d59e0ab03bf2fa661d310d
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 11 Aug 2026 19:45:47 UTC on bdcb31816ec0
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 13292
20:02:43.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.558347 29597 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:02:43.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.559794 29597 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:02:43.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.571322 29606 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
20:02:43.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.572219 29597 server_base.cc:1061] running on GCE node
20:02:43.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.571347 29603 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
20:02:43.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:43.571347 29604 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
20:02:43.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.573127 29597 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:02:43.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.573793 29597 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:02:43.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.575006 29597 hybrid_clock.cc:648] HybridClock initialized: now 1786478563574991 us; error 40 us; skew 500 ppm
20:02:43.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:43 dist-test-slave-cbsf krb5kdc[29138](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478563, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.113.131@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
20:02:43.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.578997 29597 init.cc:377] Logged in from keytab as kudu/127.28.113.131@KRBTEST.COM (short username kudu)
20:02:43.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.580289 29597 webserver.cc:533] Webserver started at http://127.28.113.131:41983/ using document root <none> and password file <none>
20:02:43.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.581035 29597 fs_manager.cc:362] Metadata directory not provided
20:02:43.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.581107 29597 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:02:43.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.581295 29597 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:02:43.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.583161 29597 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data/instance:
20:02:43.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "660f144884e942f3932f77bd65aa4890"
20:02:43.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:43 on dist-test-slave-cbsf"
20:02:43.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.583683 29597 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal/instance:
20:02:43.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "660f144884e942f3932f77bd65aa4890"
20:02:43.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:43 on dist-test-slave-cbsf"
20:02:43.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.588927 29597 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.004s	sys 0.004s
20:02:43.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.593155 29615 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:02:43.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.595357 29597 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
20:02:43.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.595522 29597 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data,/tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "660f144884e942f3932f77bd65aa4890"
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-11 20:02:43 on dist-test-slave-cbsf"
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.595633 29597 fs_report.cc:389] FS layout report
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data/data
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:02:43.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:02:43.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:02:43.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:02:43.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:02:43.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:02:43.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.612924 29597 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:02:43.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.617357 29597 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:02:43.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.617568 29597 kserver.cc:163] Server-wide thread pool size limit: 3276
20:02:43.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.618211 29597 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:02:43.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.619230 29597 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:02:43.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.619285 29597 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:02:43.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.619329 29597 ts_tablet_manager.cc:616] Registered 0 tablets
20:02:43.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.619344 29597 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:02:43.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.631881 29597 rpc_server.cc:307] RPC server started. Bound to: 127.28.113.131:37139
20:02:43.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.631908 29747 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.28.113.131:37139 every 8 connection(s)
20:02:43.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.633342 29597 server_base.cc:1193] Dumped server information to /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/data/info.pb
20:02:43.638 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.637858 29126 external_mini_cluster.cc:1434] Started /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu as pid 29597
20:02:43.638 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.637990 29126 external_mini_cluster.cc:1448] Reading /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal/instance
20:02:43.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:43 dist-test-slave-cbsf krb5kdc[29138](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786478563, etypes {rep=17 tkt=17 ses=17}, kudu/127.28.113.131@KRBTEST.COM for kudu/127.28.113.190@KRBTEST.COM
20:02:43.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.651733 29748 heartbeater.cc:344] Connected to a master server at 127.28.113.190:35945
20:02:43.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.652318 29748 heartbeater.cc:461] Registering TS with master...
20:02:43.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.653343 29748 heartbeater.cc:507] Master 127.28.113.190:35945 requested a full tablet report, sending...
20:02:43.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.654922 29197 ts_manager.cc:194] Registered new tserver with Master: 660f144884e942f3932f77bd65aa4890 (127.28.113.131:37139)
20:02:43.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.655839 29197 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.28.113.131@KRBTEST.COM'} at 127.28.113.131:34203
20:02:43.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:43.667059 29126 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
20:02:43.668 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
20:02:43.669 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

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

20:02:43.693 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.28.113.190"
      port: 35945
    }
    bound_http_address {
      host: "127.28.113.190"
      port: 34325
    }
  }
}

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

20:02:43.701 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.28.113.129"
      port: 38055
    }
    bound_http_address {
      host: "127.28.113.129"
      port: 33523
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.28.113.130"
      port: 36355
    }
    bound_http_address {
      host: "127.28.113.130"
      port: 46533
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.28.113.131"
      port: 37139
    }
    bound_http_address {
      host: "127.28.113.131"
      port: 41983
    }
  }
}

20:02:43.702 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
20:02:43.903 [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.doOneAttemptAndReport(RetryRule.java:184)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:244)
	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)
20:02:43.926 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/krb5kdc/krb5cc
20:02:43.986 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
20:02:44.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:44.081744 29424 heartbeater.cc:499] Master 127.28.113.190:35945 was elected leader, sending a full tablet report...
20:02:44.154 [DEBUG - main] (Connection.java:748) [peer master-127.28.113.190:35945(127.28.113.190:35945)] connecting to peer
20:02:44.252 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.28.113.190:35945(127.28.113.190:35945)] Successfully connected to peer
20:02:44.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:44.358980 29590 heartbeater.cc:499] Master 127.28.113.190:35945 was elected leader, sending a full tablet report...
20:02:44.363 [DEBUG - kudu-nio-0] (Negotiator.java:528) SASL mechanism GSSAPI chosen for peer 127.28.113.190
20:02:44.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 11 20:02:44 dist-test-slave-cbsf krb5kdc[29138](info): TGS_REQ (1 etypes {17}) 127.0.0.1: ISSUE: authtime 1786478562, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.28.113.190@KRBTEST.COM
20:02:44.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:44.658695 29748 heartbeater.cc:499] Master 127.28.113.190:35945 was elected leader, sending a full tablet report...
20:02:44.699 [DEBUG - kudu-nio-0] (Negotiator.java:918) Authenticated connection [id: 0xfeb58559, L:/127.0.0.1:55312 - R:/127.28.113.190:35945] using SASL/GSSAPI
20:02:44.750 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
20:02:44.751 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=662), Traces: [0ms] refreshing cache from master, [41ms] Sub RPC ConnectToMaster: sending RPC to server master-127.28.113.190:35945, [623ms] Sub RPC ConnectToMaster: received response from server master-127.28.113.190:35945: OK, deferred=null) after lookup
20:02:44.788 [DEBUG - main] (Connection.java:575) [peer master-127.28.113.190:35945(127.28.113.190:35945)] disconnecting while in state READY
20:02:44.796 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.28.113.190:35945(127.28.113.190:35945)] handling channelInactive
20:02:44.797 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.28.113.190:35945(127.28.113.190:35945)] cleaning up while in state READY due to: connection closed
20:02:44.798 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.28.113.190:35945(127.28.113.190:35945) for tablet Kudu Master: connection closed
20:02:44.812 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.28.113.190:35945(127.28.113.190:35945)] connecting to peer
20:02:44.815 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.28.113.190:35945(127.28.113.190:35945)] Successfully connected to peer
20:02:44.891 [DEBUG - kudu-nio-1] (Negotiator.java:918) Authenticated connection [id: 0x351c33a2, L:/127.0.0.1:55314 - R:/127.28.113.190:35945] using TOKEN/null
20:02:44.897 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
20:02:44.898 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=108), Traces: [0ms] sending RPC to server master-127.28.113.190:35945, [8ms] received response from server master-127.28.113.190:35945: Network error: connection closed, [9ms] delaying RPC due to: Network error: connection closed, [22ms] refreshing cache from master, [23ms] Sub RPC ConnectToMaster: sending RPC to server master-127.28.113.190:35945, [107ms] Sub RPC ConnectToMaster: received response from server master-127.28.113.190:35945: OK, deferred=Deferred@336920792(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:44.984385 29197 catalog_manager.cc:2283] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:55314:
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:02:44.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
20:02:44.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
20:02:44.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
20:02:44.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:44.989078 29197 catalog_manager.cc:7099] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table TestAuthnTokenReacquireOpen-table in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
20:02:45.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.033628 29671 tablet_service.cc:1511] Processing CreateTablet for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=eb055c9033e94576b05fcb0604d3cd4d]), partition=RANGE (key) PARTITION UNBOUNDED
20:02:45.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.035540 29671 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63. 1 dirs total, 0 dirs full, 0 dirs failed
20:02:45.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.037181 29344 tablet_service.cc:1511] Processing CreateTablet for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=eb055c9033e94576b05fcb0604d3cd4d]), partition=RANGE (key) PARTITION UNBOUNDED
20:02:45.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.038429 29344 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63. 1 dirs total, 0 dirs full, 0 dirs failed
20:02:45.045 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table eb055c9033e94576b05fcb0604d3cd4d
20:02:45.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.049000 29771 tablet_bootstrap.cc:492] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Bootstrap starting.
20:02:45.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.050907 29773 tablet_bootstrap.cc:492] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Bootstrap starting.
20:02:45.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.053197 29515 tablet_service.cc:1511] Processing CreateTablet for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=eb055c9033e94576b05fcb0604d3cd4d]), partition=RANGE (key) PARTITION UNBOUNDED
20:02:45.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.055219 29515 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63. 1 dirs total, 0 dirs full, 0 dirs failed
20:02:45.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.055547 29773 tablet_bootstrap.cc:654] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Neither blocks nor log segments found. Creating new log.
20:02:45.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.056957 29773 log.cc:826] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Log is configured to *not* fsync() on all Append() calls
20:02:45.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.059458 29771 tablet_bootstrap.cc:654] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Neither blocks nor log segments found. Creating new log.
20:02:45.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.060336 29773 tablet_bootstrap.cc:492] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: No bootstrap required, opened a new log
20:02:45.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.060653 29773 ts_tablet_manager.cc:1403] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Time spent bootstrapping tablet: real 0.010s	user 0.009s	sys 0.000s
20:02:45.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.061058 29771 log.cc:826] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Log is configured to *not* fsync() on all Append() calls
20:02:45.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.063962 29771 tablet_bootstrap.cc:492] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: No bootstrap required, opened a new log
20:02:45.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.064234 29771 ts_tablet_manager.cc:1403] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Time spent bootstrapping tablet: real 0.015s	user 0.007s	sys 0.000s
20:02:45.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.065610 29773 raft_consensus.cc:359] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.066167 29773 raft_consensus.cc:385] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:02:45.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.066430 29773 raft_consensus.cc:740] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 85ab7f08b786412c9674da89711500db, State: Initialized, Role: FOLLOWER
20:02:45.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.068255 29773 consensus_queue.cc:260] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [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: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.069391 29771 raft_consensus.cc:359] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.070214 29771 raft_consensus.cc:385] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:02:45.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.070561 29773 ts_tablet_manager.cc:1434] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Time spent starting tablet: real 0.010s	user 0.006s	sys 0.000s
20:02:45.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.070793 29771 raft_consensus.cc:740] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 660f144884e942f3932f77bd65aa4890, State: Initialized, Role: FOLLOWER
20:02:45.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.071710 29771 consensus_queue.cc:260] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [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: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.073654 29771 ts_tablet_manager.cc:1434] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Time spent starting tablet: real 0.009s	user 0.007s	sys 0.000s
20:02:45.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.075289 29776 tablet_bootstrap.cc:492] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Bootstrap starting.
20:02:45.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.079730 29776 tablet_bootstrap.cc:654] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Neither blocks nor log segments found. Creating new log.
20:02:45.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.081409 29776 log.cc:826] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Log is configured to *not* fsync() on all Append() calls
20:02:45.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.084630 29776 tablet_bootstrap.cc:492] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: No bootstrap required, opened a new log
20:02:45.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.085000 29776 ts_tablet_manager.cc:1403] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Time spent bootstrapping tablet: real 0.010s	user 0.005s	sys 0.002s
20:02:45.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.090549 29776 raft_consensus.cc:359] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.090946 29776 raft_consensus.cc:385] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:02:45.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.091017 29776 raft_consensus.cc:740] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d755b2f75ce44340b89d2f8ffc4161c4, State: Initialized, Role: FOLLOWER
20:02:45.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.091807 29776 consensus_queue.cc:260] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [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: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.093447 29776 ts_tablet_manager.cc:1434] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Time spent starting tablet: real 0.008s	user 0.002s	sys 0.005s
20:02:45.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:45.136318 29750 tablet.cc:2406] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:02:45.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.261159 29777 raft_consensus.cc:493] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
20:02:45.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.261430 29777 raft_consensus.cc:515] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.262885 29777 leader_election.cc:290] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 660f144884e942f3932f77bd65aa4890 (127.28.113.131:37139), d755b2f75ce44340b89d2f8ffc4161c4 (127.28.113.130:36355)
20:02:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.268575 29694 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "567c87f2cf0a4890bcdf75a4a5ffbc63" candidate_uuid: "85ab7f08b786412c9674da89711500db" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "660f144884e942f3932f77bd65aa4890" is_pre_election: true
20:02:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.268997 29694 raft_consensus.cc:2468] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 85ab7f08b786412c9674da89711500db in term 0.
20:02:45.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.269606 29540 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "567c87f2cf0a4890bcdf75a4a5ffbc63" candidate_uuid: "85ab7f08b786412c9674da89711500db" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" is_pre_election: true
20:02:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.269657 29295 leader_election.cc:304] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [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: 660f144884e942f3932f77bd65aa4890, 85ab7f08b786412c9674da89711500db; no voters: 
20:02:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.269903 29540 raft_consensus.cc:2468] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 85ab7f08b786412c9674da89711500db in term 0.
20:02:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.269997 29777 raft_consensus.cc:2804] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Leader pre-election won for term 1
20:02:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.270064 29777 raft_consensus.cc:493] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
20:02:45.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.270110 29777 raft_consensus.cc:3060] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 0 FOLLOWER]: Advancing to term 1
20:02:45.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.271355 29777 raft_consensus.cc:515] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.271875 29777 leader_election.cc:290] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [CANDIDATE]: Term 1 election: Requested vote from peers 660f144884e942f3932f77bd65aa4890 (127.28.113.131:37139), d755b2f75ce44340b89d2f8ffc4161c4 (127.28.113.130:36355)
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.272348 29540 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "567c87f2cf0a4890bcdf75a4a5ffbc63" candidate_uuid: "85ab7f08b786412c9674da89711500db" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d755b2f75ce44340b89d2f8ffc4161c4"
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.272493 29540 raft_consensus.cc:3060] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 0 FOLLOWER]: Advancing to term 1
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.272478 29694 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "567c87f2cf0a4890bcdf75a4a5ffbc63" candidate_uuid: "85ab7f08b786412c9674da89711500db" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "660f144884e942f3932f77bd65aa4890"
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.272612 29694 raft_consensus.cc:3060] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 0 FOLLOWER]: Advancing to term 1
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.274072 29694 raft_consensus.cc:2468] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 85ab7f08b786412c9674da89711500db in term 1.
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.274072 29540 raft_consensus.cc:2468] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 85ab7f08b786412c9674da89711500db in term 1.
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.274497 29295 leader_election.cc:304] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [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: 660f144884e942f3932f77bd65aa4890, 85ab7f08b786412c9674da89711500db; no voters: 
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.274819 29777 raft_consensus.cc:2804] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 1 FOLLOWER]: Leader election won for term 1
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.275162 29777 raft_consensus.cc:697] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 1 LEADER]: Becoming Leader. State: Replica: 85ab7f08b786412c9674da89711500db, State: Running, Role: LEADER
20:02:45.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.275563 29777 consensus_queue.cc:237] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [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: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } }
20:02:45.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.279771 29197 catalog_manager.cc:5719] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db reported cstate change: term changed from 0 to 1, leader changed from <none> to 85ab7f08b786412c9674da89711500db (127.28.113.129). New cstate: current_term: 1 leader_uuid: "85ab7f08b786412c9674da89711500db" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "85ab7f08b786412c9674da89711500db" member_type: VOTER last_known_addr { host: "127.28.113.129" port: 38055 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 } health_report { overall_health: UNKNOWN } } }
20:02:45.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:45.303984 29425 tablet.cc:2406] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:02:45.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:45.333873 29591 tablet.cc:2406] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:02:45.400 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table eb055c9033e94576b05fcb0604d3cd4d
20:02:45.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.716986 29777 consensus_queue.cc:1048] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [LEADER]: Connected to new peer: Peer: permanent_uuid: "660f144884e942f3932f77bd65aa4890" member_type: VOTER last_known_addr { host: "127.28.113.131" port: 37139 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:02:45.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:45.724960 29784 consensus_queue.cc:1048] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [LEADER]: Connected to new peer: Peer: permanent_uuid: "d755b2f75ce44340b89d2f8ffc4161c4" member_type: VOTER last_known_addr { host: "127.28.113.130" port: 36355 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:02:46.407 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table eb055c9033e94576b05fcb0604d3cd4d
20:02:46.437 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
20:02:46.444 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299993, tablet=567c87f2cf0a4890bcdf75a4a5ffbc63@[660f144884e942f3932f77bd65aa4890(127.28.113.131:37139),85ab7f08b786412c9674da89711500db(127.28.113.129:38055)[L],d755b2f75ce44340b89d2f8ffc4161c4(127.28.113.130:36355)]}]
20:02:46.455 [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=20), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.28.113.190:35945, [11ms] Sub RPC GetTableLocations: received response from server master-127.28.113.190:35945: OK, deferred=null)) after lookup
20:02:46.470 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] connecting to peer
20:02:46.473 [DEBUG - kudu-nio-2] (Connection.java:757) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] Successfully connected to peer
20:02:46.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:46.523315 29798 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:59022: token expired
20:02:46.529 [DEBUG - kudu-nio-2] (Negotiator.java:351) peer /127.28.113.129:38055 sent connection negotiation error: Not authorized: token expired
20:02:46.532 [DEBUG - kudu-nio-2] (Connection.java:239) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] handling channelInactive
20:02:46.532 [DEBUG - kudu-nio-2] (Connection.java:709) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
20:02:46.536 [DEBUG - kudu-nio-2] (Connection.java:748) [peer master-127.28.113.190:35945(127.28.113.190:35945)] connecting to peer
20:02:46.540 [DEBUG - kudu-nio-3] (Connection.java:757) [peer master-127.28.113.190:35945(127.28.113.190:35945)] Successfully connected to peer
20:02:46.552 [DEBUG - kudu-nio-3] (Negotiator.java:528) SASL mechanism GSSAPI chosen for peer 127.28.113.190
20:02:46.598 [DEBUG - kudu-nio-3] (Negotiator.java:918) Authenticated connection [id: 0x8586a49a, L:/127.0.0.1:55320 - R:/127.28.113.190:35945] using SASL/GSSAPI
20:02:46.604 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
20:02:46.606 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] connecting to peer
20:02:46.609 [DEBUG - kudu-nio-4] (Connection.java:757) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] Successfully connected to peer
20:02:46.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:46.639777 29798 fault_injection.cc:43] FAULT INJECTION ENABLED!
20:02:46.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:46.639917 29798 fault_injection.cc:44] THIS SERVER MAY CRASH!
20:02:46.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:46.640326 29798 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:59026: token signing key expired
20:02:46.642 [DEBUG - kudu-nio-4] (Negotiator.java:351) peer /127.28.113.129:38055 sent connection negotiation error: Not authorized: token signing key expired
20:02:46.643 [DEBUG - kudu-nio-4] (Connection.java:239) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] handling channelInactive
20:02:46.644 [DEBUG - kudu-nio-4] (Connection.java:709) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
20:02:46.650 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
20:02:46.652 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] connecting to peer
20:02:46.655 [DEBUG - kudu-nio-5] (Connection.java:757) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] Successfully connected to peer
20:02:46.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:46.702411 29798 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:59038: invalid token signature
20:02:46.707 [DEBUG - kudu-nio-5] (Negotiator.java:351) peer /127.28.113.129:38055 sent connection negotiation error: Not authorized: invalid token signature
20:02:46.710 [DEBUG - kudu-nio-5] (Connection.java:239) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] handling channelInactive
20:02:46.710 [DEBUG - kudu-nio-5] (Connection.java:709) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
20:02:46.714 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
20:02:46.715 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] connecting to peer
20:02:46.717 [DEBUG - kudu-nio-6] (Connection.java:757) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] Successfully connected to peer
20:02:46.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260811 20:02:46.779281 29798 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:59040: token signing key expired
20:02:46.781 [DEBUG - kudu-nio-6] (Negotiator.java:351) peer /127.28.113.129:38055 sent connection negotiation error: Not authorized: token signing key expired
20:02:46.783 [DEBUG - kudu-nio-6] (Connection.java:239) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] handling channelInactive
20:02:46.784 [DEBUG - kudu-nio-6] (Connection.java:709) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
20:02:46.789 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
20:02:46.791 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] connecting to peer
20:02:46.794 [DEBUG - kudu-nio-7] (Connection.java:757) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] Successfully connected to peer
20:02:46.833 [DEBUG - kudu-nio-7] (Negotiator.java:918) Authenticated connection [id: 0xef762549, L:/127.0.0.1:59056 - R:/127.28.113.129:38055] using TOKEN/null
20:02:46.874 [DEBUG - kudu-nio-7] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=567c87f2cf0a4890bcdf75a4a5ffbc63, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:02:46.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.892547 29197 catalog_manager.cc:2533] Servicing SoftDeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:55320:
20:02:46.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
20:02:46.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.892829 29197 catalog_manager.cc:2781] Servicing DeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:55320:
20:02:46.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
20:02:46.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.896526 29197 catalog_manager.cc:6006] T 00000000000000000000000000000000 P d0ec21115cce42b995ed80e7384a3289: Sending DeleteTablet for 3 replicas of tablet 567c87f2cf0a4890bcdf75a4a5ffbc63
20:02:46.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.898000 29344 tablet_service.cc:1558] Processing DeleteTablet for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-11 20:02:46 UTC) from {username='kudu', principal='kudu/127.28.113.190@KRBTEST.COM'} at 127.0.0.1:59018
20:02:46.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.898409 29671 tablet_service.cc:1558] Processing DeleteTablet for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-11 20:02:46 UTC) from {username='kudu', principal='kudu/127.28.113.190@KRBTEST.COM'} at 127.0.0.1:59468
20:02:46.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.899098 29515 tablet_service.cc:1558] Processing DeleteTablet for tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-11 20:02:46 UTC) from {username='kudu', principal='kudu/127.28.113.190@KRBTEST.COM'} at 127.0.0.1:42390
20:02:46.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.900061 29814 tablet_replica.cc:333] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: stopping tablet replica
20:02:46.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.900614 29815 tablet_replica.cc:333] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: stopping tablet replica
20:02:46.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.901510 29813 tablet_replica.cc:333] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: stopping tablet replica
20:02:46.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.901810 29814 raft_consensus.cc:2243] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 1 FOLLOWER]: Raft consensus shutting down.
20:02:46.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.902082 29813 raft_consensus.cc:2243] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 1 LEADER]: Raft consensus shutting down.
20:02:46.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.902467 29815 raft_consensus.cc:2243] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 1 FOLLOWER]: Raft consensus shutting down.
20:02:46.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.902875 29813 raft_consensus.cc:2272] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db [term 1 FOLLOWER]: Raft consensus is shut down!
20:02:46.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.903249 29814 raft_consensus.cc:2272] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890 [term 1 FOLLOWER]: Raft consensus is shut down!
20:02:46.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.903852 29815 raft_consensus.cc:2272] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4 [term 1 FOLLOWER]: Raft consensus is shut down!
20:02:46.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.904579 29813 ts_tablet_manager.cc:1916] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Deleting tablet data with delete state TABLET_DATA_DELETED
20:02:46.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.906155 29815 ts_tablet_manager.cc:1916] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Deleting tablet data with delete state TABLET_DATA_DELETED
20:02:46.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.907732 29814 ts_tablet_manager.cc:1916] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Deleting tablet data with delete state TABLET_DATA_DELETED
20:02:46.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.908075 29813 ts_tablet_manager.cc:1929] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
20:02:46.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.910514 29813 log.cc:1199] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Deleting WAL directory at /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-0/wal/wals/567c87f2cf0a4890bcdf75a4a5ffbc63
20:02:46.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.910857 29815 ts_tablet_manager.cc:1929] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
20:02:46.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.911230 29815 log.cc:1199] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Deleting WAL directory at /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-1/wal/wals/567c87f2cf0a4890bcdf75a4a5ffbc63
20:02:46.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.911288 29813 ts_tablet_manager.cc:1950] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 85ab7f08b786412c9674da89711500db: Deleting consensus metadata
20:02:46.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.911912 29815 ts_tablet_manager.cc:1950] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P d755b2f75ce44340b89d2f8ffc4161c4: Deleting consensus metadata
20:02:46.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.912891 29181 catalog_manager.cc:5050] TS 85ab7f08b786412c9674da89711500db (127.28.113.129:38055): tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 (table TestAuthnTokenReacquireOpen-table [id=eb055c9033e94576b05fcb0604d3cd4d]) successfully deleted
20:02:46.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.914561 29178 catalog_manager.cc:5050] TS d755b2f75ce44340b89d2f8ffc4161c4 (127.28.113.130:36355): tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 (table TestAuthnTokenReacquireOpen-table [id=eb055c9033e94576b05fcb0604d3cd4d]) successfully deleted
20:02:46.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.915619 29814 ts_tablet_manager.cc:1929] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
20:02:46.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.915755 29814 log.cc:1199] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Deleting WAL directory at /tmp/dist-test-tasko3XzJb/test-tmp/mini-kudu-cluster6908696404792442614/ts-2/wal/wals/567c87f2cf0a4890bcdf75a4a5ffbc63
20:02:46.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.916147 29814 ts_tablet_manager.cc:1950] T 567c87f2cf0a4890bcdf75a4a5ffbc63 P 660f144884e942f3932f77bd65aa4890: Deleting consensus metadata
20:02:46.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.918478 29181 catalog_manager.cc:5050] TS 660f144884e942f3932f77bd65aa4890 (127.28.113.131:37139): tablet 567c87f2cf0a4890bcdf75a4a5ffbc63 (table TestAuthnTokenReacquireOpen-table [id=eb055c9033e94576b05fcb0604d3cd4d]) successfully deleted
20:02:46.919 [DEBUG - main] (Connection.java:575) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] disconnecting while in state READY
20:02:46.920 [DEBUG - main] (Connection.java:575) [peer master-127.28.113.190:35945(127.28.113.190:35945)] disconnecting while in state READY
20:02:46.920 [DEBUG - main] (Connection.java:575) [peer master-127.28.113.190:35945(127.28.113.190:35945)] disconnecting while in state READY
20:02:46.921 [DEBUG - kudu-nio-7] (Connection.java:239) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] handling channelInactive
20:02:46.922 [DEBUG - kudu-nio-7] (Connection.java:709) [peer 85ab7f08b786412c9674da89711500db(127.28.113.129:38055)] cleaning up while in state READY due to: connection closed
20:02:46.922 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.28.113.190:35945(127.28.113.190:35945)] handling channelInactive
20:02:46.922 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.28.113.190:35945(127.28.113.190:35945)] cleaning up while in state READY due to: connection closed
20:02:46.923 [DEBUG - kudu-nio-3] (AsyncKuduClient.java:2704) Releasing all remaining resources
20:02:46.930 [DEBUG - kudu-nio-3] (Connection.java:239) [peer master-127.28.113.190:35945(127.28.113.190:35945)] handling channelInactive
20:02:46.931 [DEBUG - kudu-nio-3] (Connection.java:709) [peer master-127.28.113.190:35945(127.28.113.190:35945)] cleaning up while in state READY due to: connection closed
20:02:46.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.930943 29126 external_mini_cluster.cc:1664] Killing /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu with pid 29273
20:02:46.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.946851 29126 external_mini_cluster.cc:1664] Killing /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu with pid 29434
20:02:46.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.959976 29126 external_mini_cluster.cc:1664] Killing /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu with pid 29597
20:02:46.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260811 20:02:46.976460 29126 external_mini_cluster.cc:1664] Killing /tmp/dist-test-tasko3XzJb/build/debug/bin/kudu with pid 29158
20:02:47.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-11T20:02:47Z chronyd exiting

Time: 7.772

OK (1 test)

