JUnit version 4.13.2
.08:03:40.915 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1779091420905
08:03:40.950 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
08:03:41.020 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
08:03:41.027 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskdH4K4t/test-tmp
08:03:41.033 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
08:03:41.035 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskdH4K4t/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
08:03:41.236 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553"
  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 {
  }
}

08:03:41.419 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
08:03:41.420 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

08:03:41.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:41.425999  3212 mini_oidc.cc:150] Starting JWKS server
08:03:41.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:41.431262  3212 webserver.cc:533] Webserver started at http://127.0.0.1:36187/ using document root <none> and password file <none>
08:03:41.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:41.431874  3212 mini_oidc.cc:186] Starting OIDC Discovery server
08:03:41.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:41.434018  3212 webserver.cc:533] Webserver started at http://127.0.0.1:43715/ using document root <none> and password file <none>
08:03:41.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
08:03:41.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/principal' for realm 'KRBTEST.COM',
08:03:41.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
08:03:41.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:41 dist-test-slave-9p8q krb5kdc[3223](info): setting up network...
08:03:41.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
08:03:41.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:41 dist-test-slave-9p8q krb5kdc[3223](info): set up 2 sockets
08:03:41.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:41 dist-test-slave-9p8q krb5kdc[3223](info): commencing operation
08:03:41.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
08:03:43.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:43.483633  3212 mini_kdc.cc:121] Time spent starting KDC: real 2.011s	user 0.001s	sys 0.018s
08:03:43.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
08:03:43.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:03:43.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
08:03:43.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
08:03:43.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:03:43.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
08:03:43.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
08:03:43.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:03:43.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
08:03:43.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
08:03:43.588 [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-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/test-user.keytab.
08:03:43.588 [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-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/test-user.keytab.
08:03:43.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:43 dist-test-slave-9p8q krb5kdc[3223](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091423, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:03:43.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
08:03:43.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T08:03:43Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
08:03:43.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T08:03:43Z Disabled control of system clock
08:03:43.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.35.62@KRBTEST.COM; defaulting to no policy
08:03:43.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:43.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.35.62@KRBTEST.COM" created.
08:03:43.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:43.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:43.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:43.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.35.62@KRBTEST.COM; defaulting to no policy
08:03:43.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:43.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.35.62@KRBTEST.COM" created.
08:03:43.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:43.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.62 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:43.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.62 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:43.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:43.710887  3212 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:43.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:43.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/wal
08:03:43.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data
08:03:43.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:03:43.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:03:43.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:03:43.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:43.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:43.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:43.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/logs
08:03:43.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data/info.pb
08:03:43.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:43.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:03:43.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:03:43.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:03:43.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:03:43.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:43.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:43.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:43.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
08:03:43.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:03:43.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:03:43.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:03:43.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.62:45787
08:03:43.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.62
08:03:43.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:43.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:43.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.62
08:03:43.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:43.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:03:43.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:03:43.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
08:03:43.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:43.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:43.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:43.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:43.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:03:43.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.3.35.62:45787
08:03:43.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:03:43.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:36187/jwks.json
08:03:43.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
08:03:43.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
08:03:43.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:03:44.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.018759  3239 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:03:44.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.019318  3239 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:03:44.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.020038  3239 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:03:44.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.053340  3239 flags.cc:432] Enabled experimental flag: --authn_token_validity_seconds=1
08:03:44.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.053619  3239 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
08:03:44.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.053885  3239 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:03:44.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.054150  3239 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
08:03:44.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.054351  3239 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
08:03:44.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.054621  3239 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
08:03:44.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.054774  3239 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:36187/jwks.json
08:03:44.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:44.094086  3239 master_runner.cc:387] Master server non-default flags:
08:03:44.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:44.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:44.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:44.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:44.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data
08:03:44.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/wal
08:03:44.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
08:03:44.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
08:03:44.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.3.35.62:45787
08:03:44.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:44.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.62
08:03:44.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:44.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:44.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
08:03:44.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.62:45787
08:03:44.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
08:03:44.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:03:44.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
08:03:44.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:36187/jwks.json
08:03:44.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:44.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
08:03:44.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:03:44.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:03:44.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:44.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:44.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data/info.pb
08:03:44.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.62
08:03:44.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:44.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:03:44.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/logs
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:03:44.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
08:03:44.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:03:44.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
08:03:44.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:03:44.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:25 UTC on e7f111948823
08:03:44.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11955
08:03:44.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:03:44.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:44.095408  3239 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:03:44.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:44.096740  3239 file_cache.cc:492] Constructed file cache file cache with capacity 419430
08:03:44.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.110491  3245 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
08:03:45.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:45.514173  3244 debug-util.cc:398] Leaking SignalData structure 0x7b0800034ac0 after lost signal to thread 3239
08:03:45.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:45.870780  3244 kernel_stack_watchdog.cc:198] Thread 3239 stuck at /home/jenkins-slave/workspace/build_and_test_flaky@2/src/kudu/util/thread.cc:642 for 401ms:
08:03:45.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
08:03:45.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
08:03:45.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
08:03:45.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
08:03:45.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:45.872130  3239 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.762s	user 0.721s	sys 1.040s
08:03:45.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:45.872274  3247 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1759 milliseconds
08:03:45.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:45.872478  3239 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.762s	user 0.721s	sys 1.040s
08:03:45.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:44.110863  3246 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
08:03:45.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:45.873839  3248 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
08:03:45.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.874117  3239 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
08:03:45.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.878834  3239 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:03:45.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.881932  3239 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:03:45.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.883359  3239 hybrid_clock.cc:648] HybridClock initialized: now 1779091425883327 us; error 48 us; skew 500 ppm
08:03:45.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.884116  3239 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:03:45.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:45 dist-test-slave-9p8q krb5kdc[3223](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091425, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.35.62@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:03:45.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.890260  3239 init.cc:377] Logged in from keytab as kudu/127.3.35.62@KRBTEST.COM (short username kudu)
08:03:45.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.895321  3239 webserver.cc:533] Webserver started at http://127.3.35.62:36839/ using document root <none> and password file <none>
08:03:45.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.896463  3239 fs_manager.cc:362] Metadata directory not provided
08:03:45.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.896713  3239 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:03:45.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.897325  3239 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:03:45.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.901765  3239 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data/instance:
08:03:45.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "050caaf05a414d66bb3827bf66e77d52"
08:03:45.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:45 on dist-test-slave-9p8q"
08:03:45.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.903152  3239 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/wal/instance:
08:03:45.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "050caaf05a414d66bb3827bf66e77d52"
08:03:45.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:45 on dist-test-slave-9p8q"
08:03:45.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.910615  3239 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.008s	sys 0.000s
08:03:45.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.916138  3256 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:03:45.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.917363  3239 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
08:03:45.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.917733  3239 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data,/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/wal
08:03:45.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "050caaf05a414d66bb3827bf66e77d52"
08:03:45.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:45 on dist-test-slave-9p8q"
08:03:45.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.918179  3239 fs_report.cc:389] FS layout report
08:03:45.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:03:45.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/wal
08:03:45.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/wal
08:03:45.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data/data
08:03:45.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:03:45.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:03:45.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:03:45.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:03:45.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:03:45.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:03:45.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:03:45.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:03:45.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:03:45.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:03:45.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:03:45.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.979486  3239 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:03:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.985541  3239 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:03:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:45.986114  3239 kserver.cc:163] Server-wide thread pool size limit: 3276
08:03:46.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.056294  3239 rpc_server.cc:307] RPC server started. Bound to: 127.3.35.62:45787
08:03:46.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.056377  3307 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.35.62:45787 every 8 connection(s)
08:03:46.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.059075  3239 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/data/info.pb
08:03:46.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.065248  3308 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
08:03:46.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.066547  3212 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu as pid 3239
08:03:46.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.066926  3212 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/master-0/wal/instance
08:03:46.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.086674  3308 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52: Bootstrap starting.
08:03:46.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.092924  3308 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52: Neither blocks nor log segments found. Creating new log.
08:03:46.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.096303  3308 log.cc:826] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52: Log is configured to *not* fsync() on all Append() calls
08:03:46.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:46 dist-test-slave-9p8q krb5kdc[3223](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091423, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.3.35.62@KRBTEST.COM
08:03:46.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.102682  3308 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52: No bootstrap required, opened a new log
08:03:46.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.122049  3308 raft_consensus.cc:359] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "050caaf05a414d66bb3827bf66e77d52" member_type: VOTER last_known_addr { host: "127.3.35.62" port: 45787 } }
08:03:46.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.122941  3308 raft_consensus.cc:385] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:03:46.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.123230  3308 raft_consensus.cc:740] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 050caaf05a414d66bb3827bf66e77d52, State: Initialized, Role: FOLLOWER
08:03:46.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.124040  3308 consensus_queue.cc:260] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [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: "050caaf05a414d66bb3827bf66e77d52" member_type: VOTER last_known_addr { host: "127.3.35.62" port: 45787 } }
08:03:46.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.124799  3308 raft_consensus.cc:399] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
08:03:46.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.125196  3308 raft_consensus.cc:493] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
08:03:46.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.125572  3308 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 0 FOLLOWER]: Advancing to term 1
08:03:46.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.130672  3308 raft_consensus.cc:515] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "050caaf05a414d66bb3827bf66e77d52" member_type: VOTER last_known_addr { host: "127.3.35.62" port: 45787 } }
08:03:46.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.131317  3308 leader_election.cc:304] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [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: 050caaf05a414d66bb3827bf66e77d52; no voters: 
08:03:46.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.133285  3308 leader_election.cc:290] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [CANDIDATE]: Term 1 election: Requested vote from peers 
08:03:46.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.133546  3313 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 1 FOLLOWER]: Leader election won for term 1
08:03:46.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.137609  3313 raft_consensus.cc:697] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [term 1 LEADER]: Becoming Leader. State: Replica: 050caaf05a414d66bb3827bf66e77d52, State: Running, Role: LEADER
08:03:46.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.138392  3308 sys_catalog.cc:565] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [sys.catalog]: configured and running, proceeding with master startup.
08:03:46.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.138463  3313 consensus_queue.cc:237] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [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: "050caaf05a414d66bb3827bf66e77d52" member_type: VOTER last_known_addr { host: "127.3.35.62" port: 45787 } }
08:03:46.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.149628  3315 sys_catalog.cc:455] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 050caaf05a414d66bb3827bf66e77d52. Latest consensus state: current_term: 1 leader_uuid: "050caaf05a414d66bb3827bf66e77d52" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "050caaf05a414d66bb3827bf66e77d52" member_type: VOTER last_known_addr { host: "127.3.35.62" port: 45787 } } }
08:03:46.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.150362  3315 sys_catalog.cc:458] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [sys.catalog]: This master's current role is: LEADER
08:03:46.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.150326  3314 sys_catalog.cc:455] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "050caaf05a414d66bb3827bf66e77d52" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "050caaf05a414d66bb3827bf66e77d52" member_type: VOTER last_known_addr { host: "127.3.35.62" port: 45787 } } }
08:03:46.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.151202  3314 sys_catalog.cc:458] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52 [sys.catalog]: This master's current role is: LEADER
08:03:46.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.163570  3321 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
08:03:46.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.176843  3321 catalog_manager.cc:1519] Initializing Kudu cluster ID...
08:03:46.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.196110  3321 catalog_manager.cc:1382] Generated new cluster ID: 5d62635f6fec45c19debca9933454548
08:03:46.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.196388  3321 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
08:03:46.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.215848  3321 catalog_manager.cc:1405] Generated new certificate authority record
08:03:46.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.218233  3321 catalog_manager.cc:1539] Loading token signing keys...
08:03:46.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.230513  3321 catalog_manager.cc:6069] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52: Generated new TSK 0
08:03:46.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.231575  3321 catalog_manager.cc:1549] Initializing in-progress tserver states...
08:03:46.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.35.1@KRBTEST.COM; defaulting to no policy
08:03:46.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:46.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.35.1@KRBTEST.COM" created.
08:03:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:46.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:46.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:46.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.35.1@KRBTEST.COM; defaulting to no policy
08:03:46.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:46.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.35.1@KRBTEST.COM" created.
08:03:46.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:46.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.1 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:46.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.1 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:46.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.329525  3212 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:46.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:46.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal
08:03:46.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data
08:03:46.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:03:46.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:03:46.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:03:46.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:46.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:46.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:46.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/logs
08:03:46.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data/info.pb
08:03:46.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:46.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:03:46.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:03:46.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:03:46.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:03:46.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:46.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:46.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:46.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:03:46.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:03:46.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.1:0
08:03:46.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.35.1
08:03:46.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.1
08:03:46.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:46.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.35.62:45787
08:03:46.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:46.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.1
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:46.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:46.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
08:03:46.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
08:03:46.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:03:46.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.639104  3336 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
08:03:46.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.639654  3336 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:03:46.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.639982  3336 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:03:46.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.640964  3336 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:03:46.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.679929  3336 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:03:46.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.680833  3336 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.3.35.1
08:03:46.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.718950  3336 tablet_server_runner.cc:78] Tablet server non-default flags:
08:03:46.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:46.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:46.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:46.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:46.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.1
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.1:0
08:03:46.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data/info.pb
08:03:46.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.1
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.35.62:45787
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:46.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.35.1
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/logs
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
08:03:46.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:03:46.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:25 UTC on e7f111948823
08:03:46.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11955
08:03:46.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:03:46.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.720255  3336 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:03:46.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:46.721396  3336 file_cache.cc:492] Constructed file cache file cache with capacity 419430
08:03:46.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.735934  3342 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
08:03:48.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:48.138391  3341 debug-util.cc:398] Leaking SignalData structure 0x7b0800037380 after lost signal to thread 3336
08:03:48.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:48.594396  3336 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.857s	user 0.730s	sys 1.045s
08:03:48.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:48.594760  3336 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.858s	user 0.730s	sys 1.045s
08:03:48.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:46.737021  3343 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
08:03:48.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:48.597086  3345 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
08:03:48.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:48.602804  3344 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1863 milliseconds
08:03:48.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.602856  3336 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
08:03:48.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.604779  3336 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:03:48.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.607363  3336 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:03:48.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.609385  3336 hybrid_clock.cc:648] HybridClock initialized: now 1779091428609358 us; error 38 us; skew 500 ppm
08:03:48.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.610149  3336 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:03:48.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:48 dist-test-slave-9p8q krb5kdc[3223](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091428, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.35.1@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:03:48.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.616200  3336 init.cc:377] Logged in from keytab as kudu/127.3.35.1@KRBTEST.COM (short username kudu)
08:03:48.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.621515  3336 webserver.cc:533] Webserver started at http://127.3.35.1:45641/ using document root <none> and password file <none>
08:03:48.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.622372  3336 fs_manager.cc:362] Metadata directory not provided
08:03:48.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.622560  3336 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:03:48.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.622923  3336 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:03:48.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.627326  3336 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data/instance:
08:03:48.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ed036e46e3ce44108df57a6eef4a2f3e"
08:03:48.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:48 on dist-test-slave-9p8q"
08:03:48.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.628356  3336 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal/instance:
08:03:48.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ed036e46e3ce44108df57a6eef4a2f3e"
08:03:48.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:48 on dist-test-slave-9p8q"
08:03:48.636 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.635895  3336 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.008s	sys 0.002s
08:03:48.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.642100  3353 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:03:48.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.644631  3336 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.003s	sys 0.002s
08:03:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.645004  3336 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data,/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal
08:03:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "ed036e46e3ce44108df57a6eef4a2f3e"
08:03:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:48 on dist-test-slave-9p8q"
08:03:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.645339  3336 fs_report.cc:389] FS layout report
08:03:48.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data/data
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:03:48.646 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:03:48.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:03:48.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:03:48.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:03:48.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:03:48.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:03:48.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.708690  3336 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:03:48.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.714476  3336 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:03:48.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.714998  3336 kserver.cc:163] Server-wide thread pool size limit: 3276
08:03:48.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.718278  3336 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
08:03:48.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.723050  3336 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:03:48.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.723294  3336 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
08:03:48.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.723595  3336 ts_tablet_manager.cc:616] Registered 0 tablets
08:03:48.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.723824  3336 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:03:48.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.890969  3336 rpc_server.cc:307] RPC server started. Bound to: 127.3.35.1:44641
08:03:48.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.891084  3465 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.35.1:44641 every 8 connection(s)
08:03:48.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.894178  3336 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/data/info.pb
08:03:48.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.900758  3212 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu as pid 3336
08:03:48.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.901269  3212 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal/instance
08:03:48.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.35.2@KRBTEST.COM; defaulting to no policy
08:03:48.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:48.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.35.2@KRBTEST.COM" created.
08:03:48.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:48 dist-test-slave-9p8q krb5kdc[3223](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091428, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.35.1@KRBTEST.COM for kudu/127.3.35.62@KRBTEST.COM
08:03:48.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.950603  3466 heartbeater.cc:344] Connected to a master server at 127.3.35.62:45787
08:03:48.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.951140  3466 heartbeater.cc:461] Registering TS with master...
08:03:48.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.952291  3466 heartbeater.cc:507] Master 127.3.35.62:45787 requested a full tablet report, sending...
08:03:48.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.955399  3273 ts_manager.cc:194] Registered new tserver with Master: ed036e46e3ce44108df57a6eef4a2f3e (127.3.35.1:44641)
08:03:48.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:48.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:48.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:48.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.957784  3273 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.3.35.1@KRBTEST.COM'} at 127.3.35.1:37877
08:03:48.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.35.2@KRBTEST.COM; defaulting to no policy
08:03:48.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:48.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.35.2@KRBTEST.COM" created.
08:03:48.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:48.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.2 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:48.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.2 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:48.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:48.997221  3212 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:48.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:48.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/logs
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data/info.pb
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:48.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.2:0
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.35.2
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.2
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:48.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.35.62:45787
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.2
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
08:03:49.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:03:49.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.324569  3474 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
08:03:49.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.325230  3474 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:03:49.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.325496  3474 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:03:49.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.326032  3474 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:03:49.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.359769  3474 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:03:49.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.360682  3474 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.3.35.2
08:03:49.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:49.399148  3474 tablet_server_runner.cc:78] Tablet server non-default flags:
08:03:49.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:49.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:49.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.2
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.2:0
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
08:03:49.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data/info.pb
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.2
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.35.62:45787
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.35.2
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/logs
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:25 UTC on e7f111948823
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11955
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:49.400390  3474 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:03:49.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:49.401895  3474 file_cache.cc:492] Constructed file cache file cache with capacity 419430
08:03:49.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.417783  3480 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
08:03:49.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:49.961838  3466 heartbeater.cc:499] Master 127.3.35.62:45787 was elected leader, sending a full tablet report...
08:03:50.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.419870  3481 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
08:03:50.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:49.422749  3483 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
08:03:50.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:50.588716  3482 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
08:03:50.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.589136  3474 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
08:03:50.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.594728  3474 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:03:50.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.597186  3474 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:03:50.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.598587  3474 hybrid_clock.cc:648] HybridClock initialized: now 1779091430598548 us; error 63 us; skew 500 ppm
08:03:50.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.599664  3474 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:03:50.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:50 dist-test-slave-9p8q krb5kdc[3223](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091430, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.35.2@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:03:50.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.606675  3474 init.cc:377] Logged in from keytab as kudu/127.3.35.2@KRBTEST.COM (short username kudu)
08:03:50.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.611662  3474 webserver.cc:533] Webserver started at http://127.3.35.2:40445/ using document root <none> and password file <none>
08:03:50.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.612519  3474 fs_manager.cc:362] Metadata directory not provided
08:03:50.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.612706  3474 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:03:50.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.613190  3474 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:03:50.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.617858  3474 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data/instance:
08:03:50.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "71ed614e7d734677a83f24fadc235529"
08:03:50.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:50 on dist-test-slave-9p8q"
08:03:50.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.618944  3474 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal/instance:
08:03:50.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "71ed614e7d734677a83f24fadc235529"
08:03:50.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:50 on dist-test-slave-9p8q"
08:03:50.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.626377  3474 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.004s	sys 0.004s
08:03:50.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.631748  3491 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:03:50.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.632808  3474 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.000s
08:03:50.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.633206  3474 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data,/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal
08:03:50.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "71ed614e7d734677a83f24fadc235529"
08:03:50.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:50 on dist-test-slave-9p8q"
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.633654  3474 fs_report.cc:389] FS layout report
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data/data
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:03:50.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:03:50.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:03:50.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.687665  3474 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:03:50.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.696848  3474 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:03:50.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.697420  3474 kserver.cc:163] Server-wide thread pool size limit: 3276
08:03:50.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.700186  3474 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
08:03:50.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.704327  3474 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:03:50.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.704586  3474 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
08:03:50.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.704912  3474 ts_tablet_manager.cc:616] Registered 0 tablets
08:03:50.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.705165  3474 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.001s	sys 0.000s
08:03:50.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.838373  3474 rpc_server.cc:307] RPC server started. Bound to: 127.3.35.2:43225
08:03:50.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.838492  3603 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.35.2:43225 every 8 connection(s)
08:03:50.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.841387  3474 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/data/info.pb
08:03:50.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.850188  3212 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu as pid 3474
08:03:50.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.850526  3212 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal/instance
08:03:50.863 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:50 dist-test-slave-9p8q krb5kdc[3223](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091430, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.35.2@KRBTEST.COM for kudu/127.3.35.62@KRBTEST.COM
08:03:50.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.3.35.3@KRBTEST.COM; defaulting to no policy
08:03:50.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:50.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.3.35.3@KRBTEST.COM" created.
08:03:50.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.877045  3604 heartbeater.cc:344] Connected to a master server at 127.3.35.62:45787
08:03:50.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.877537  3604 heartbeater.cc:461] Registering TS with master...
08:03:50.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.878736  3604 heartbeater.cc:507] Master 127.3.35.62:45787 requested a full tablet report, sending...
08:03:50.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.881019  3273 ts_manager.cc:194] Registered new tserver with Master: 71ed614e7d734677a83f24fadc235529 (127.3.35.2:43225)
08:03:50.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.882395  3273 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.3.35.2@KRBTEST.COM'} at 127.3.35.2:60905
08:03:50.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:50.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:50.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.3.35.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:50.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.3.35.3@KRBTEST.COM; defaulting to no policy
08:03:50.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:50.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.3.35.3@KRBTEST.COM" created.
08:03:50.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
08:03:50.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.3 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:50.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.3.35.3 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab.
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:50.934659  3212 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/logs
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data/info.pb
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
08:03:50.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.3:0
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.35.3
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.3
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.35.62:45787
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.3
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
08:03:50.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:50.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:50.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:50.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:50.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
08:03:50.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
08:03:50.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
08:03:51.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.256470  3612 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
08:03:51.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.257084  3612 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
08:03:51.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.257304  3612 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
08:03:51.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.257827  3612 flags.cc:432] Enabled unsafe flag: --never_fsync=true
08:03:51.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.291828  3612 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
08:03:51.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.292821  3612 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.3.35.3
08:03:51.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:51.330811  3612 tablet_server_runner.cc:78] Tablet server non-default flags:
08:03:51.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
08:03:51.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.3.35.20:43855
08:03:51.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
08:03:51.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
08:03:51.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/kudu.keytab
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.3.35.3
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.3.35.3:0
08:03:51.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data/info.pb
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.3.35.3
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.3.35.62:45787
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
08:03:51.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.3.35.3
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/logs
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:25 UTC on e7f111948823
08:03:51.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11955
08:03:51.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
08:03:51.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:51.332131  3612 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
08:03:51.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:51.333352  3612 file_cache.cc:492] Constructed file cache file cache with capacity 419430
08:03:51.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.346336  3618 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
08:03:51.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:51.885591  3604 heartbeater.cc:499] Master 127.3.35.62:45787 was elected leader, sending a full tablet report...
08:03:52.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:51.347018  3619 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
08:03:52.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:52.533375  3620 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
08:03:52.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.534790  3612 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
08:03:52.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:52.534786  3621 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
08:03:52.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.537735  3612 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
08:03:52.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.539988  3612 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
08:03:52.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.541332  3612 hybrid_clock.cc:648] HybridClock initialized: now 1779091432541309 us; error 56 us; skew 500 ppm
08:03:52.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.542091  3612 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
08:03:52.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:52 dist-test-slave-9p8q krb5kdc[3223](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091432, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.35.3@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
08:03:52.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.548002  3612 init.cc:377] Logged in from keytab as kudu/127.3.35.3@KRBTEST.COM (short username kudu)
08:03:52.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.553711  3612 webserver.cc:533] Webserver started at http://127.3.35.3:39063/ using document root <none> and password file <none>
08:03:52.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.554736  3612 fs_manager.cc:362] Metadata directory not provided
08:03:52.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.554941  3612 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
08:03:52.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.555325  3612 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
08:03:52.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.559576  3612 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data/instance:
08:03:52.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "41dcf31b3d534afca0d3831070c0eae0"
08:03:52.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:52 on dist-test-slave-9p8q"
08:03:52.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.560604  3612 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal/instance:
08:03:52.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "41dcf31b3d534afca0d3831070c0eae0"
08:03:52.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:52 on dist-test-slave-9p8q"
08:03:52.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.567731  3612 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.005s	sys 0.000s
08:03:52.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.573364  3630 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
08:03:52.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.574576  3612 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.002s
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.574882  3612 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data,/tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "41dcf31b3d534afca0d3831070c0eae0"
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 08:03:52 on dist-test-slave-9p8q"
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.575182  3612 fs_report.cc:389] FS layout report
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data/data
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
08:03:52.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
08:03:52.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
08:03:52.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
08:03:52.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
08:03:52.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
08:03:52.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
08:03:52.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
08:03:52.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.629047  3612 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
08:03:52.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.634413  3612 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
08:03:52.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.634843  3612 kserver.cc:163] Server-wide thread pool size limit: 3276
08:03:52.637 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.637573  3612 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
08:03:52.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.641781  3612 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
08:03:52.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.641984  3612 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
08:03:52.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.642184  3612 ts_tablet_manager.cc:616] Registered 0 tablets
08:03:52.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.642313  3612 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
08:03:52.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.777760  3612 rpc_server.cc:307] RPC server started. Bound to: 127.3.35.3:37563
08:03:52.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.777879  3742 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.3.35.3:37563 every 8 connection(s)
08:03:52.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.780481  3612 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/data/info.pb
08:03:52.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.789951  3212 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu as pid 3612
08:03:52.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.790674  3212 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal/instance
08:03:52.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:52 dist-test-slave-9p8q krb5kdc[3223](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091432, etypes {rep=17 tkt=17 ses=17}, kudu/127.3.35.3@KRBTEST.COM for kudu/127.3.35.62@KRBTEST.COM
08:03:52.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.814379  3743 heartbeater.cc:344] Connected to a master server at 127.3.35.62:45787
08:03:52.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.814821  3743 heartbeater.cc:461] Registering TS with master...
08:03:52.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.815963  3743 heartbeater.cc:507] Master 127.3.35.62:45787 requested a full tablet report, sending...
08:03:52.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.818117  3273 ts_manager.cc:194] Registered new tserver with Master: 41dcf31b3d534afca0d3831070c0eae0 (127.3.35.3:37563)
08:03:52.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.819327  3273 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.3.35.3@KRBTEST.COM'} at 127.3.35.3:57697
08:03:52.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:52.825929  3212 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
08:03:52.837 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
08:03:52.839 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

08:03:52.851 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

08:03:52.858 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.3.35.62"
      port: 45787
    }
    bound_http_address {
      host: "127.3.35.62"
      port: 36839
    }
  }
}

08:03:52.861 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

08:03:52.864 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.3.35.1"
      port: 44641
    }
    bound_http_address {
      host: "127.3.35.1"
      port: 45641
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.3.35.2"
      port: 43225
    }
    bound_http_address {
      host: "127.3.35.2"
      port: 40445
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.3.35.3"
      port: 37563
    }
    bound_http_address {
      host: "127.3.35.3"
      port: 39063
    }
  }
}

08:03:52.864 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
08:03:53.055 [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)
08:03:53.087 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/krb5kdc/krb5cc
08:03:53.132 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
08:03:53.242 [DEBUG - main] (Connection.java:748) [peer master-127.3.35.62:45787(127.3.35.62:45787)] connecting to peer
08:03:53.309 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.3.35.62:45787(127.3.35.62:45787)] Successfully connected to peer
08:03:53.402 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.3.35.62
08:03:53.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 08:03:53 dist-test-slave-9p8q krb5kdc[3223](info): TGS_REQ (1 etypes {17}) 127.0.0.1: ISSUE: authtime 1779091423, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.3.35.62@KRBTEST.COM
08:03:53.660 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xd93f500d, L:/127.0.0.1:53650 - R:/127.3.35.62:45787] using SASL/GSSAPI
08:03:53.696 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
08:03:53.697 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=495), Traces: [0ms] refreshing cache from master, [26ms] Sub RPC ConnectToMaster: sending RPC to server master-127.3.35.62:45787, [469ms] Sub RPC ConnectToMaster: received response from server master-127.3.35.62:45787: OK, deferred=null) after lookup
08:03:53.719 [DEBUG - main] (Connection.java:575) [peer master-127.3.35.62:45787(127.3.35.62:45787)] disconnecting while in state READY
08:03:53.726 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.3.35.62:45787(127.3.35.62:45787)] handling channelInactive
08:03:53.727 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.3.35.62:45787(127.3.35.62:45787)] cleaning up while in state READY due to: connection closed
08:03:53.728 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.3.35.62:45787(127.3.35.62:45787) for tablet Kudu Master: connection closed
08:03:53.743 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.3.35.62:45787(127.3.35.62:45787)] connecting to peer
08:03:53.746 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.3.35.62:45787(127.3.35.62:45787)] Successfully connected to peer
08:03:53.807 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xa9f3ebd6, L:/127.0.0.1:53660 - R:/127.3.35.62:45787] using TOKEN/null
08:03:53.811 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
08:03:53.811 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=90), Traces: [0ms] sending RPC to server master-127.3.35.62:45787, [7ms] received response from server master-127.3.35.62:45787: Network error: connection closed, [7ms] delaying RPC due to: Network error: connection closed, [21ms] refreshing cache from master, [22ms] Sub RPC ConnectToMaster: sending RPC to server master-127.3.35.62:45787, [89ms] Sub RPC ConnectToMaster: received response from server master-127.3.35.62:45787: OK, deferred=Deferred@1926904033(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
08:03:53.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:53.822458  3743 heartbeater.cc:499] Master 127.3.35.62:45787 was elected leader, sending a full tablet report...
08:03:53.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:53.886274  3273 catalog_manager.cc:2282] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:53660:
08:03:53.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
08:03:53.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
08:03:53.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
08:03:53.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
08:03:53.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
08:03:53.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:53.919041  3273 catalog_manager.cc:7058] 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.
08:03:53.976 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 375f346413e043588f499f82ed486676
08:03:53.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:53.987881  3678 tablet_service.cc:1511] Processing CreateTablet for tablet 65ccac33928740bb8ddd949fc8c2bc7e (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=375f346413e043588f499f82ed486676]), partition=RANGE (key) PARTITION UNBOUNDED
08:03:53.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:53.990329  3678 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 65ccac33928740bb8ddd949fc8c2bc7e. 1 dirs total, 0 dirs full, 0 dirs failed
08:03:54.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.001821  3539 tablet_service.cc:1511] Processing CreateTablet for tablet 65ccac33928740bb8ddd949fc8c2bc7e (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=375f346413e043588f499f82ed486676]), partition=RANGE (key) PARTITION UNBOUNDED
08:03:54.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.004392  3539 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 65ccac33928740bb8ddd949fc8c2bc7e. 1 dirs total, 0 dirs full, 0 dirs failed
08:03:54.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.028213  3762 tablet_bootstrap.cc:492] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Bootstrap starting.
08:03:54.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.038259  3762 tablet_bootstrap.cc:654] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Neither blocks nor log segments found. Creating new log.
08:03:54.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.041147  3762 log.cc:826] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Log is configured to *not* fsync() on all Append() calls
08:03:54.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.041594  3401 tablet_service.cc:1511] Processing CreateTablet for tablet 65ccac33928740bb8ddd949fc8c2bc7e (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=375f346413e043588f499f82ed486676]), partition=RANGE (key) PARTITION UNBOUNDED
08:03:54.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.043705  3401 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 65ccac33928740bb8ddd949fc8c2bc7e. 1 dirs total, 0 dirs full, 0 dirs failed
08:03:54.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.045564  3763 tablet_bootstrap.cc:492] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Bootstrap starting.
08:03:54.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.052541  3763 tablet_bootstrap.cc:654] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Neither blocks nor log segments found. Creating new log.
08:03:54.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.053673  3762 tablet_bootstrap.cc:492] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: No bootstrap required, opened a new log
08:03:54.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.054515  3762 ts_tablet_manager.cc:1403] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Time spent bootstrapping tablet: real 0.027s	user 0.014s	sys 0.007s
08:03:54.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.055593  3763 log.cc:826] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Log is configured to *not* fsync() on all Append() calls
08:03:54.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.075520  3763 tablet_bootstrap.cc:492] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: No bootstrap required, opened a new log
08:03:54.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.079159  3766 tablet_bootstrap.cc:492] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Bootstrap starting.
08:03:54.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.089359  3763 ts_tablet_manager.cc:1403] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Time spent bootstrapping tablet: real 0.044s	user 0.008s	sys 0.013s
08:03:54.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.097353  3766 tablet_bootstrap.cc:654] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Neither blocks nor log segments found. Creating new log.
08:03:54.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.100286  3766 log.cc:826] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Log is configured to *not* fsync() on all Append() calls
08:03:54.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.101385  3762 raft_consensus.cc:359] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.106263  3762 raft_consensus.cc:385] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:03:54.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.106994  3762 raft_consensus.cc:740] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 41dcf31b3d534afca0d3831070c0eae0, State: Initialized, Role: FOLLOWER
08:03:54.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.115134  3766 tablet_bootstrap.cc:492] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: No bootstrap required, opened a new log
08:03:54.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.116086  3766 ts_tablet_manager.cc:1403] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Time spent bootstrapping tablet: real 0.038s	user 0.015s	sys 0.007s
08:03:54.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.108513  3762 consensus_queue.cc:260] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [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: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.131053  3762 ts_tablet_manager.cc:1434] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Time spent starting tablet: real 0.076s	user 0.029s	sys 0.007s
08:03:54.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.127990  3763 raft_consensus.cc:359] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.133190  3763 raft_consensus.cc:385] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:03:54.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.133761  3763 raft_consensus.cc:740] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 71ed614e7d734677a83f24fadc235529, State: Initialized, Role: FOLLOWER
08:03:54.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.134922  3763 consensus_queue.cc:260] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [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: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.151805  3763 ts_tablet_manager.cc:1434] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Time spent starting tablet: real 0.062s	user 0.045s	sys 0.000s
08:03:54.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.153537  3766 raft_consensus.cc:359] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.154764  3766 raft_consensus.cc:385] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
08:03:54.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.155072  3766 raft_consensus.cc:740] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: ed036e46e3ce44108df57a6eef4a2f3e, State: Initialized, Role: FOLLOWER
08:03:54.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.155943  3766 consensus_queue.cc:260] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [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: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.159936  3766 ts_tablet_manager.cc:1434] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Time spent starting tablet: real 0.043s	user 0.035s	sys 0.001s
08:03:54.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:54.287474  3744 tablet.cc:2404] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:03:54.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:54.349713  3605 tablet.cc:2404] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:03:54.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.369742  3769 raft_consensus.cc:493] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
08:03:54.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.370232  3769 raft_consensus.cc:515] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.373030  3769 leader_election.cc:290] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 41dcf31b3d534afca0d3831070c0eae0 (127.3.35.3:37563), ed036e46e3ce44108df57a6eef4a2f3e (127.3.35.1:44641)
08:03:54.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.386062  3698 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "65ccac33928740bb8ddd949fc8c2bc7e" candidate_uuid: "71ed614e7d734677a83f24fadc235529" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "41dcf31b3d534afca0d3831070c0eae0" is_pre_election: true
08:03:54.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.386538  3421 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "65ccac33928740bb8ddd949fc8c2bc7e" candidate_uuid: "71ed614e7d734677a83f24fadc235529" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" is_pre_election: true
08:03:54.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.387033  3698 raft_consensus.cc:2468] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 71ed614e7d734677a83f24fadc235529 in term 0.
08:03:54.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.387420  3421 raft_consensus.cc:2468] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 71ed614e7d734677a83f24fadc235529 in term 0.
08:03:54.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.388623  3495 leader_election.cc:304] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [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: 41dcf31b3d534afca0d3831070c0eae0, 71ed614e7d734677a83f24fadc235529; no voters: 
08:03:54.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.389539  3769 raft_consensus.cc:2804] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Leader pre-election won for term 1
08:03:54.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.389891  3769 raft_consensus.cc:493] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
08:03:54.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.390187  3769 raft_consensus.cc:3060] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 0 FOLLOWER]: Advancing to term 1
08:03:54.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.395516  3769 raft_consensus.cc:515] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.396868  3769 leader_election.cc:290] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [CANDIDATE]: Term 1 election: Requested vote from peers 41dcf31b3d534afca0d3831070c0eae0 (127.3.35.3:37563), ed036e46e3ce44108df57a6eef4a2f3e (127.3.35.1:44641)
08:03:54.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.397884  3698 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "65ccac33928740bb8ddd949fc8c2bc7e" candidate_uuid: "71ed614e7d734677a83f24fadc235529" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "41dcf31b3d534afca0d3831070c0eae0"
08:03:54.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.397889  3421 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "65ccac33928740bb8ddd949fc8c2bc7e" candidate_uuid: "71ed614e7d734677a83f24fadc235529" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "ed036e46e3ce44108df57a6eef4a2f3e"
08:03:54.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.398272  3698 raft_consensus.cc:3060] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 0 FOLLOWER]: Advancing to term 1
08:03:54.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.398353  3421 raft_consensus.cc:3060] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 0 FOLLOWER]: Advancing to term 1
08:03:54.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.402796  3421 raft_consensus.cc:2468] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 71ed614e7d734677a83f24fadc235529 in term 1.
08:03:54.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.403340  3698 raft_consensus.cc:2468] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 71ed614e7d734677a83f24fadc235529 in term 1.
08:03:54.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.403963  3494 leader_election.cc:304] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [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: 71ed614e7d734677a83f24fadc235529, ed036e46e3ce44108df57a6eef4a2f3e; no voters: 
08:03:54.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:54.404553  3467 tablet.cc:2404] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Can't schedule compaction. Clean time has not been advanced past its initial value.
08:03:54.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.404810  3769 raft_consensus.cc:2804] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 1 FOLLOWER]: Leader election won for term 1
08:03:54.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.406352  3769 raft_consensus.cc:697] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 1 LEADER]: Becoming Leader. State: Replica: 71ed614e7d734677a83f24fadc235529, State: Running, Role: LEADER
08:03:54.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.407202  3769 consensus_queue.cc:237] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [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: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } }
08:03:54.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.418689  3272 catalog_manager.cc:5696] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 reported cstate change: term changed from 0 to 1, leader changed from <none> to 71ed614e7d734677a83f24fadc235529 (127.3.35.2). New cstate: current_term: 1 leader_uuid: "71ed614e7d734677a83f24fadc235529" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "71ed614e7d734677a83f24fadc235529" member_type: VOTER last_known_addr { host: "127.3.35.2" port: 43225 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 } health_report { overall_health: UNKNOWN } } }
08:03:54.531 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 375f346413e043588f499f82ed486676
08:03:54.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.931845  3775 consensus_queue.cc:1048] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [LEADER]: Connected to new peer: Peer: permanent_uuid: "41dcf31b3d534afca0d3831070c0eae0" member_type: VOTER last_known_addr { host: "127.3.35.3" port: 37563 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
08:03:54.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:54.952515  3775 consensus_queue.cc:1048] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [LEADER]: Connected to new peer: Peer: permanent_uuid: "ed036e46e3ce44108df57a6eef4a2f3e" member_type: VOTER last_known_addr { host: "127.3.35.1" port: 44641 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
08:03:55.537 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 375f346413e043588f499f82ed486676
08:03:55.561 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 65ccac33928740bb8ddd949fc8c2bc7e for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
08:03:55.567 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=65ccac33928740bb8ddd949fc8c2bc7e@[41dcf31b3d534afca0d3831070c0eae0(127.3.35.3:37563),71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)[L],ed036e46e3ce44108df57a6eef4a2f3e(127.3.35.1:44641)]}]
08:03:55.577 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC ScanRequest(scannerId=null, state=OPENING, attempt=1, KuduRpc(method=Scan, tablet=null, attempt=1, TimeoutTracker(timeout=60000, elapsed=17), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.3.35.62:45787, [9ms] Sub RPC GetTableLocations: received response from server master-127.3.35.62:45787: OK, deferred=null)) after lookup
08:03:55.588 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] connecting to peer
08:03:55.591 [DEBUG - kudu-nio-2] (Connection.java:757) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] Successfully connected to peer
08:03:55.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:55.638854  3789 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:54858: token expired
08:03:55.644 [DEBUG - kudu-nio-2] (Negotiator.java:352) peer /127.3.35.2:43225 sent connection negotiation error: Not authorized: token expired
08:03:55.646 [DEBUG - kudu-nio-2] (Connection.java:239) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] handling channelInactive
08:03:55.647 [DEBUG - kudu-nio-2] (Connection.java:709) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
08:03:55.648 [DEBUG - kudu-nio-2] (Connection.java:748) [peer master-127.3.35.62:45787(127.3.35.62:45787)] connecting to peer
08:03:55.651 [DEBUG - kudu-nio-3] (Connection.java:757) [peer master-127.3.35.62:45787(127.3.35.62:45787)] Successfully connected to peer
08:03:55.662 [DEBUG - kudu-nio-3] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.3.35.62
08:03:55.702 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x33f16488, L:/127.0.0.1:53664 - R:/127.3.35.62:45787] using SASL/GSSAPI
08:03:55.706 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
08:03:55.707 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] connecting to peer
08:03:55.709 [DEBUG - kudu-nio-4] (Connection.java:757) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] Successfully connected to peer
08:03:55.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:55.733760  3789 fault_injection.cc:43] FAULT INJECTION ENABLED!
08:03:55.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 08:03:55.734037  3789 fault_injection.cc:44] THIS SERVER MAY CRASH!
08:03:55.736 [DEBUG - kudu-nio-4] (Negotiator.java:919) Authenticated connection [id: 0x60bb30f1, L:/127.0.0.1:54864 - R:/127.3.35.2:43225] using TOKEN/null
08:03:55.773 [DEBUG - kudu-nio-4] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=65ccac33928740bb8ddd949fc8c2bc7e, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
08:03:55.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.792168  3273 catalog_manager.cc:2532] Servicing SoftDeleteTable request from {username='test-admin'} at 127.0.0.1:53660:
08:03:55.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
08:03:55.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.792810  3273 catalog_manager.cc:2780] Servicing DeleteTable request from {username='test-admin'} at 127.0.0.1:53660:
08:03:55.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
08:03:55.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.809065  3273 catalog_manager.cc:5983] T 00000000000000000000000000000000 P 050caaf05a414d66bb3827bf66e77d52: Sending DeleteTablet for 3 replicas of tablet 65ccac33928740bb8ddd949fc8c2bc7e
08:03:55.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.812031  3539 tablet_service.cc:1558] Processing DeleteTablet for tablet 65ccac33928740bb8ddd949fc8c2bc7e with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 08:03:55 UTC) from {username='kudu', principal='kudu/127.3.35.62@KRBTEST.COM'} at 127.0.0.1:54844
08:03:55.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.812093  3678 tablet_service.cc:1558] Processing DeleteTablet for tablet 65ccac33928740bb8ddd949fc8c2bc7e with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 08:03:55 UTC) from {username='kudu', principal='kudu/127.3.35.62@KRBTEST.COM'} at 127.0.0.1:58558
08:03:55.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.817834  3401 tablet_service.cc:1558] Processing DeleteTablet for tablet 65ccac33928740bb8ddd949fc8c2bc7e with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 08:03:55 UTC) from {username='kudu', principal='kudu/127.3.35.62@KRBTEST.COM'} at 127.0.0.1:42126
08:03:55.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.826041  3801 tablet_replica.cc:333] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: stopping tablet replica
08:03:55.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.828866  3801 raft_consensus.cc:2243] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 1 FOLLOWER]: Raft consensus shutting down.
08:03:55.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.829970  3801 raft_consensus.cc:2272] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e [term 1 FOLLOWER]: Raft consensus is shut down!
08:03:55.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.834347  3801 ts_tablet_manager.cc:1916] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Deleting tablet data with delete state TABLET_DATA_DELETED
08:03:55.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.837389  3799 tablet_replica.cc:333] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: stopping tablet replica
08:03:55.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.838403  3799 raft_consensus.cc:2243] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 1 FOLLOWER]: Raft consensus shutting down.
08:03:55.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.843468  3799 raft_consensus.cc:2272] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0 [term 1 FOLLOWER]: Raft consensus is shut down!
08:03:55.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.847865  3799 ts_tablet_manager.cc:1916] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Deleting tablet data with delete state TABLET_DATA_DELETED
08:03:55.853 [DEBUG - main] (Connection.java:575) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] disconnecting while in state READY
08:03:55.853 [DEBUG - main] (Connection.java:575) [peer master-127.3.35.62:45787(127.3.35.62:45787)] disconnecting while in state READY
08:03:55.854 [DEBUG - main] (Connection.java:575) [peer master-127.3.35.62:45787(127.3.35.62:45787)] disconnecting while in state READY
08:03:55.856 [DEBUG - kudu-nio-4] (Connection.java:239) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] handling channelInactive
08:03:55.856 [DEBUG - kudu-nio-4] (Connection.java:709) [peer 71ed614e7d734677a83f24fadc235529(127.3.35.2:43225)] cleaning up while in state READY due to: connection closed
08:03:55.857 [DEBUG - kudu-nio-3] (Connection.java:239) [peer master-127.3.35.62:45787(127.3.35.62:45787)] handling channelInactive
08:03:55.857 [DEBUG - kudu-nio-3] (Connection.java:709) [peer master-127.3.35.62:45787(127.3.35.62:45787)] cleaning up while in state READY due to: connection closed
08:03:55.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.857851  3801 ts_tablet_manager.cc:1929] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
08:03:55.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.858469  3801 log.cc:1199] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Deleting WAL directory at /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-0/wal/wals/65ccac33928740bb8ddd949fc8c2bc7e
08:03:55.859 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2704) Releasing all remaining resources
08:03:55.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.859440  3801 ts_tablet_manager.cc:1950] T 65ccac33928740bb8ddd949fc8c2bc7e P ed036e46e3ce44108df57a6eef4a2f3e: Deleting consensus metadata
08:03:55.862 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.861912  3800 tablet_replica.cc:333] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: stopping tablet replica
08:03:55.864 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.864553  3800 raft_consensus.cc:2243] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 1 LEADER]: Raft consensus shutting down.
08:03:55.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.865954  3800 raft_consensus.cc:2272] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529 [term 1 FOLLOWER]: Raft consensus is shut down!
08:03:55.869 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.3.35.62:45787(127.3.35.62:45787)] handling channelInactive
08:03:55.869 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.3.35.62:45787(127.3.35.62:45787)] cleaning up while in state READY due to: connection closed
08:03:55.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.869839  3212 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu with pid 3336
08:03:55.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.870434  3259 catalog_manager.cc:5027] TS ed036e46e3ce44108df57a6eef4a2f3e (127.3.35.1:44641): tablet 65ccac33928740bb8ddd949fc8c2bc7e (table TestAuthnTokenReacquireOpen-table [id=375f346413e043588f499f82ed486676]) successfully deleted
08:03:55.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.871479  3800 ts_tablet_manager.cc:1916] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Deleting tablet data with delete state TABLET_DATA_DELETED
08:03:55.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.874289  3799 ts_tablet_manager.cc:1929] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
08:03:55.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.875356  3799 log.cc:1199] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Deleting WAL directory at /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-2/wal/wals/65ccac33928740bb8ddd949fc8c2bc7e
08:03:55.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.876327  3799 ts_tablet_manager.cc:1950] T 65ccac33928740bb8ddd949fc8c2bc7e P 41dcf31b3d534afca0d3831070c0eae0: Deleting consensus metadata
08:03:55.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.882145  3260 catalog_manager.cc:5027] TS 41dcf31b3d534afca0d3831070c0eae0 (127.3.35.3:37563): tablet 65ccac33928740bb8ddd949fc8c2bc7e (table TestAuthnTokenReacquireOpen-table [id=375f346413e043588f499f82ed486676]) successfully deleted
08:03:55.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.890578  3800 ts_tablet_manager.cc:1929] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
08:03:55.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.891299  3800 log.cc:1199] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Deleting WAL directory at /tmp/dist-test-taskdH4K4t/test-tmp/mini-kudu-cluster4649020932458756553/ts-1/wal/wals/65ccac33928740bb8ddd949fc8c2bc7e
08:03:55.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.892320  3800 ts_tablet_manager.cc:1950] T 65ccac33928740bb8ddd949fc8c2bc7e P 71ed614e7d734677a83f24fadc235529: Deleting consensus metadata
08:03:55.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.896077  3259 catalog_manager.cc:5027] TS 71ed614e7d734677a83f24fadc235529 (127.3.35.2:43225): tablet 65ccac33928740bb8ddd949fc8c2bc7e (table TestAuthnTokenReacquireOpen-table [id=375f346413e043588f499f82ed486676]) successfully deleted
08:03:55.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.907315  3212 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu with pid 3474
08:03:55.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.937340  3212 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu with pid 3612
08:03:55.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 08:03:55.964354  3212 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskdH4K4t/build/tsan/bin/kudu with pid 3239
08:03:57.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T08:03:57Z chronyd exiting

Time: 17.526

OK (1 test)

