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

02:03:55.702 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
02:03:55.704 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

02:03:55.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:55.707773 32343 mini_oidc.cc:150] Starting JWKS server
02:03:55.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:55.711931 32343 webserver.cc:533] Webserver started at http://127.0.0.1:39133/ using document root <none> and password file <none>
02:03:55.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:55.712355 32343 mini_oidc.cc:186] Starting OIDC Discovery server
02:03:55.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:55.714452 32343 webserver.cc:533] Webserver started at http://127.0.0.1:43281/ using document root <none> and password file <none>
02:03:55.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
02:03:55.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/principal' for realm 'KRBTEST.COM',
02:03:55.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
02:03:55.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:03:55 dist-test-slave-kkjj krb5kdc[32355](info): setting up network...
02:03:55.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
02:03:55.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:03:55 dist-test-slave-kkjj krb5kdc[32355](info): set up 2 sockets
02:03:55.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:03:55 dist-test-slave-kkjj krb5kdc[32355](info): commencing operation
02:03:55.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
02:03:57.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:57.804176 32343 mini_kdc.cc:121] Time spent starting KDC: real 2.052s	user 0.000s	sys 0.019s
02:03:57.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
02:03:57.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:03:57.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
02:03:57.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
02:03:57.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:03:57.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
02:03:57.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
02:03:57.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
02:03:57.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
02:03:57.911 [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-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/test-user.keytab.
02:03:57.911 [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-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/test-user.keytab.
02:03:57.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:03:57 dist-test-slave-kkjj krb5kdc[32355](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069837, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:03:57.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
02:03:57.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T02:03:57Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
02:03:57.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T02:03:57Z Disabled control of system clock
02:03:57.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.149.254@KRBTEST.COM; defaulting to no policy
02:03:57.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:03:57.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.149.254@KRBTEST.COM" created.
02:03:58.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:03:58.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.254 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:03:58.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.254 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:03:58.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.31.149.254@KRBTEST.COM; defaulting to no policy
02:03:58.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:03:58.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.31.149.254@KRBTEST.COM" created.
02:03:58.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:03:58.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.254 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:03:58.055 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.254 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:03:58.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:58.057156 32343 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:03:58.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:03:58.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/wal
02:03:58.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data
02:03:58.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:03:58.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:03:58.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:03:58.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:03:58.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:03:58.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:03:58.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/logs
02:03:58.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data/info.pb
02:03:58.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:03:58.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:03:58.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:03:58.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:03:58.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:03:58.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:03:58.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:03:58.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:03:58.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
02:03:58.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:03:58.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
02:03:58.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
02:03:58.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.254:40723
02:03:58.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.254
02:03:58.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:03:58.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:03:58.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.254
02:03:58.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:03:58.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:03:58.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:03:58.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:03:58.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
02:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.149.254:40723
02:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
02:03:58.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:39133/jwks.json
02:03:58.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:03:58.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
02:03:58.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:03:58.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.379398 32371 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:03:58.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.380242 32371 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:03:58.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.381392 32371 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:03:58.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.417891 32371 flags.cc:432] Enabled experimental flag: --authn_token_validity_seconds=1
02:03:58.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.418140 32371 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
02:03:58.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.418406 32371 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:03:58.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.418606 32371 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
02:03:58.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.418938 32371 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
02:03:58.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.419309 32371 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
02:03:58.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.419560 32371 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:39133/jwks.json
02:03:58.460 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:58.460008 32371 master_runner.cc:387] Master server non-default flags:
02:03:58.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:03:58.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:03:58.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:03:58.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:03:58.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data
02:03:58.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/wal
02:03:58.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
02:03:58.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
02:03:58.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.31.149.254:40723
02:03:58.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:03:58.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.254
02:03:58.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:03:58.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:03:58.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
02:03:58.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.254:40723
02:03:58.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
02:03:58.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:03:58.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
02:03:58.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:39133/jwks.json
02:03:58.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:03:58.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:03:58.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:03:58.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:03:58.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:03:58.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:03:58.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data/info.pb
02:03:58.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.254
02:03:58.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:03:58.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:03:58.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:03:58.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:03:58.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:03:58.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:03:58.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/logs
02:03:58.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:03:58.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:03:58.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:03:58.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
02:03:58.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:03:58.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
02:03:58.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:03:58.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 01:43:23 UTC on e7f111948823
02:03:58.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11951
02:03:58.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:03:58.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:58.461354 32371 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:03:58.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:58.462741 32371 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:03:58.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.478639 32378 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:03:59.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:58.478662 32377 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:03:59.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:59.747439 32380 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:03:59.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:59.752530 32379 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1272 milliseconds
02:03:59.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:59.752843 32371 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.275s	user 0.417s	sys 0.852s
02:03:59.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:03:59.753192 32371 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.276s	user 0.417s	sys 0.852s
02:03:59.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.753379 32371 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
02:03:59.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.754513 32371 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:03:59.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.757516 32371 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:03:59.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.758967 32371 hybrid_clock.cc:648] HybridClock initialized: now 1779069839758924 us; error 65 us; skew 500 ppm
02:03:59.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.760061 32371 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:03:59.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:03:59 dist-test-slave-kkjj krb5kdc[32355](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069839, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.149.254@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:03:59.767 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.767516 32371 init.cc:377] Logged in from keytab as kudu/127.31.149.254@KRBTEST.COM (short username kudu)
02:03:59.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.774528 32371 webserver.cc:533] Webserver started at http://127.31.149.254:41927/ using document root <none> and password file <none>
02:03:59.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.775745 32371 fs_manager.cc:362] Metadata directory not provided
02:03:59.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.776016 32371 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:03:59.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.776628 32371 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:03:59.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.781630 32371 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data/instance:
02:03:59.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d4db7becbf884f8dae110e75fe3cafe2"
02:03:59.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:03:59 on dist-test-slave-kkjj"
02:03:59.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.783123 32371 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/wal/instance:
02:03:59.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d4db7becbf884f8dae110e75fe3cafe2"
02:03:59.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:03:59 on dist-test-slave-kkjj"
02:03:59.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.792124 32371 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.006s	sys 0.001s
02:03:59.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.798744 32388 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:03:59.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.800200 32371 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.001s	sys 0.002s
02:03:59.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.800616 32371 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data,/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/wal
02:03:59.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d4db7becbf884f8dae110e75fe3cafe2"
02:03:59.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:03:59 on dist-test-slave-kkjj"
02:03:59.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.801084 32371 fs_report.cc:389] FS layout report
02:03:59.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:03:59.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/wal
02:03:59.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/wal
02:03:59.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data/data
02:03:59.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:03:59.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:03:59.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:03:59.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:03:59.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:03:59.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:03:59.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:03:59.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:03:59.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:03:59.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:03:59.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:03:59.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.878536 32371 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:03:59.884 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.884310 32371 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:03:59.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.884727 32371 kserver.cc:163] Server-wide thread pool size limit: 3276
02:03:59.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.969734 32371 rpc_server.cc:307] RPC server started. Bound to: 127.31.149.254:40723
02:03:59.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.969810 32439 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.149.254:40723 every 8 connection(s)
02:03:59.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.973941 32371 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/data/info.pb
02:03:59.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.976388 32343 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu as pid 32371
02:03:59.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.977037 32343 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/master-0/wal/instance
02:03:59.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:03:59.981720 32440 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
02:04:00.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.012609 32440 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2: Bootstrap starting.
02:04:00.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:00 dist-test-slave-kkjj krb5kdc[32355](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069837, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.31.149.254@KRBTEST.COM
02:04:00.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.021164 32440 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2: Neither blocks nor log segments found. Creating new log.
02:04:00.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.022881 32440 log.cc:826] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2: Log is configured to *not* fsync() on all Append() calls
02:04:00.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.029209 32440 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2: No bootstrap required, opened a new log
02:04:00.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.050391 32440 raft_consensus.cc:359] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4db7becbf884f8dae110e75fe3cafe2" member_type: VOTER last_known_addr { host: "127.31.149.254" port: 40723 } }
02:04:00.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.051769 32440 raft_consensus.cc:385] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:04:00.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.052008 32440 raft_consensus.cc:740] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d4db7becbf884f8dae110e75fe3cafe2, State: Initialized, Role: FOLLOWER
02:04:00.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.052714 32440 consensus_queue.cc:260] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [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: "d4db7becbf884f8dae110e75fe3cafe2" member_type: VOTER last_known_addr { host: "127.31.149.254" port: 40723 } }
02:04:00.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.053377 32440 raft_consensus.cc:399] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
02:04:00.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.053721 32440 raft_consensus.cc:493] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
02:04:00.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.054144 32440 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 0 FOLLOWER]: Advancing to term 1
02:04:00.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.060915 32440 raft_consensus.cc:515] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4db7becbf884f8dae110e75fe3cafe2" member_type: VOTER last_known_addr { host: "127.31.149.254" port: 40723 } }
02:04:00.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.061913 32440 leader_election.cc:304] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [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: d4db7becbf884f8dae110e75fe3cafe2; no voters: 
02:04:00.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.064056 32440 leader_election.cc:290] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [CANDIDATE]: Term 1 election: Requested vote from peers 
02:04:00.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.064467 32445 raft_consensus.cc:2804] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 1 FOLLOWER]: Leader election won for term 1
02:04:00.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.067553 32445 raft_consensus.cc:697] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [term 1 LEADER]: Becoming Leader. State: Replica: d4db7becbf884f8dae110e75fe3cafe2, State: Running, Role: LEADER
02:04:00.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.068861 32445 consensus_queue.cc:237] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [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: "d4db7becbf884f8dae110e75fe3cafe2" member_type: VOTER last_known_addr { host: "127.31.149.254" port: 40723 } }
02:04:00.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.069273 32440 sys_catalog.cc:565] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [sys.catalog]: configured and running, proceeding with master startup.
02:04:00.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.079003 32446 sys_catalog.cc:455] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d4db7becbf884f8dae110e75fe3cafe2" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4db7becbf884f8dae110e75fe3cafe2" member_type: VOTER last_known_addr { host: "127.31.149.254" port: 40723 } } }
02:04:00.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.079121 32447 sys_catalog.cc:455] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [sys.catalog]: SysCatalogTable state changed. Reason: New leader d4db7becbf884f8dae110e75fe3cafe2. Latest consensus state: current_term: 1 leader_uuid: "d4db7becbf884f8dae110e75fe3cafe2" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d4db7becbf884f8dae110e75fe3cafe2" member_type: VOTER last_known_addr { host: "127.31.149.254" port: 40723 } } }
02:04:00.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.080592 32447 sys_catalog.cc:458] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [sys.catalog]: This master's current role is: LEADER
02:04:00.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.080581 32446 sys_catalog.cc:458] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2 [sys.catalog]: This master's current role is: LEADER
02:04:00.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.089017 32453 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
02:04:00.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.101521 32453 catalog_manager.cc:1519] Initializing Kudu cluster ID...
02:04:00.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.121979 32453 catalog_manager.cc:1382] Generated new cluster ID: 43a62a031ec541f7bfcc2b227663020f
02:04:00.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.122309 32453 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
02:04:00.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.169589 32453 catalog_manager.cc:1405] Generated new certificate authority record
02:04:00.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.172060 32453 catalog_manager.cc:1539] Loading token signing keys...
02:04:00.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.191030 32453 catalog_manager.cc:6069] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2: Generated new TSK 0
02:04:00.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.192203 32453 catalog_manager.cc:1549] Initializing in-progress tserver states...
02:04:00.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.149.193@KRBTEST.COM; defaulting to no policy
02:04:00.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:00.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.149.193@KRBTEST.COM" created.
02:04:00.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:00.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.193 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:00.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.193 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:00.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.31.149.193@KRBTEST.COM; defaulting to no policy
02:04:00.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:00.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.31.149.193@KRBTEST.COM" created.
02:04:00.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:00.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.193 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:00.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.193 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:00.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.304669 32343 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:04:00.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:04:00.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/wal
02:04:00.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data
02:04:00.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:04:00.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:04:00.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:04:00.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:04:00.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:04:00.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:04:00.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/logs
02:04:00.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data/info.pb
02:04:00.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:04:00.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:04:00.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:04:00.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:04:00.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:04:00.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:04:00.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:04:00.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:04:00.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
02:04:00.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:04:00.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.193:0
02:04:00.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.149.193
02:04:00.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.193
02:04:00.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:04:00.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.149.254:40723
02:04:00.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:04:00.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.193
02:04:00.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:04:00.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:04:00.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:04:00.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:04:00.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:04:00.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:04:00.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:04:00.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:04:00.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:04:00.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:04:00.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:04:00.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.640208 32468 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
02:04:00.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.640776 32468 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:04:00.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.641049 32468 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:04:00.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.641601 32468 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:04:00.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.676323 32468 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:04:00.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.677397 32468 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.149.193
02:04:00.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.715821 32468 tablet_server_runner.cc:78] Tablet server non-default flags:
02:04:00.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:04:00.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:04:00.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:04:00.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:04:00.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data
02:04:00.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/wal
02:04:00.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
02:04:00.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:04:00.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.193
02:04:00.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:04:00.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:04:00.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.193:0
02:04:00.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:04:00.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:04:00.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:04:00.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:04:00.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:04:00.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:04:00.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:04:00.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data/info.pb
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.193
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.149.254:40723
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:04:00.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.149.193
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/logs
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:04:00.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
02:04:00.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:04:00.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 01:43:23 UTC on e7f111948823
02:04:00.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11951
02:04:00.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:04:00.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.717408 32468 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:04:00.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:00.718923 32468 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:04:00.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.737679 32475 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:02.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:02.138511 32473 debug-util.cc:398] Leaking SignalData structure 0x7b0800037380 after lost signal to thread 32468
02:04:02.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:02.585616 32473 kernel_stack_watchdog.cc:198] Thread 32468 stuck at /home/jenkins-slave/workspace/build_and_test_flaky@2/src/kudu/util/thread.cc:642 for 396ms:
02:04:02.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
02:04:02.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
02:04:02.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
02:04:02.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
02:04:02.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:00.742408 32474 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:02.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:02.589984 32477 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:02.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:02.589166 32468 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.849s	user 0.687s	sys 1.139s
02:04:02.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:02.590615 32468 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.851s	user 0.687s	sys 1.140s
02:04:02.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:02.594998 32476 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1850 milliseconds
02:04:02.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.595031 32468 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
02:04:02.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.596657 32468 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:04:02.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.599786 32468 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:04:02.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.601300 32468 hybrid_clock.cc:648] HybridClock initialized: now 1779069842601235 us; error 80 us; skew 500 ppm
02:04:02.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.602391 32468 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:04:02.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:02 dist-test-slave-kkjj krb5kdc[32355](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069842, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.149.193@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:04:02.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.609687 32468 init.cc:377] Logged in from keytab as kudu/127.31.149.193@KRBTEST.COM (short username kudu)
02:04:02.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.616194 32468 webserver.cc:533] Webserver started at http://127.31.149.193:46243/ using document root <none> and password file <none>
02:04:02.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.617141 32468 fs_manager.cc:362] Metadata directory not provided
02:04:02.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.617343 32468 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:04:02.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.617729 32468 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:04:02.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.622309 32468 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data/instance:
02:04:02.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e1e6eaf701ae4a1981d66f9e35358b48"
02:04:02.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:02 on dist-test-slave-kkjj"
02:04:02.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.623631 32468 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/wal/instance:
02:04:02.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e1e6eaf701ae4a1981d66f9e35358b48"
02:04:02.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:02 on dist-test-slave-kkjj"
02:04:02.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.631831 32468 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.005s	sys 0.002s
02:04:02.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.639459 32485 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:04:02.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.641491 32468 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.002s	sys 0.002s
02:04:02.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.641952 32468 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data,/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/wal
02:04:02.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e1e6eaf701ae4a1981d66f9e35358b48"
02:04:02.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:02 on dist-test-slave-kkjj"
02:04:02.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.642453 32468 fs_report.cc:389] FS layout report
02:04:02.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:04:02.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/wal
02:04:02.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/wal
02:04:02.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data/data
02:04:02.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:04:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:04:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:04:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:04:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:04:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:04:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:04:02.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:04:02.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:04:02.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:04:02.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:04:02.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.701180 32468 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:04:02.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.711884 32468 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:04:02.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.712354 32468 kserver.cc:163] Server-wide thread pool size limit: 3276
02:04:02.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.716351 32468 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
02:04:02.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.720749 32468 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
02:04:02.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.720970 32468 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
02:04:02.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.721169 32468 ts_tablet_manager.cc:616] Registered 0 tablets
02:04:02.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.721293 32468 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
02:04:02.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.897936 32468 rpc_server.cc:307] RPC server started. Bound to: 127.31.149.193:45555
02:04:02.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.897991 32597 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.149.193:45555 every 8 connection(s)
02:04:02.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.900811 32468 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/data/info.pb
02:04:02.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.910254 32343 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu as pid 32468
02:04:02.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.910728 32343 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-0/wal/instance
02:04:02.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:02 dist-test-slave-kkjj krb5kdc[32355](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069842, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.149.193@KRBTEST.COM for kudu/127.31.149.254@KRBTEST.COM
02:04:02.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.149.194@KRBTEST.COM; defaulting to no policy
02:04:02.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:02.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.149.194@KRBTEST.COM" created.
02:04:02.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.942371 32598 heartbeater.cc:344] Connected to a master server at 127.31.149.254:40723
02:04:02.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.942960 32598 heartbeater.cc:461] Registering TS with master...
02:04:02.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.944537 32598 heartbeater.cc:507] Master 127.31.149.254:40723 requested a full tablet report, sending...
02:04:02.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.948371 32405 ts_manager.cc:194] Registered new tserver with Master: e1e6eaf701ae4a1981d66f9e35358b48 (127.31.149.193:45555)
02:04:02.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:02.951656 32405 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.31.149.193@KRBTEST.COM'} at 127.31.149.193:60425
02:04:02.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:02.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.194 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:02.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.194 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:02.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.31.149.194@KRBTEST.COM; defaulting to no policy
02:04:02.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:02.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.31.149.194@KRBTEST.COM" created.
02:04:03.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:03.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.194 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:03.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.194 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:03.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:03.022215 32343 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:04:03.022 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/logs
02:04:03.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data/info.pb
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
02:04:03.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.194:0
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.149.194
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.194
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.149.254:40723
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.194
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:04:03.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:04:03.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:04:03.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:04:03.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:04:03.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:04:03.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:04:03.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:04:03.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:04:03.348 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.348140 32606 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
02:04:03.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.348712 32606 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:04:03.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.348910 32606 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:04:03.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.349469 32606 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:04:03.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.384943 32606 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:04:03.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.385977 32606 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.149.194
02:04:03.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:03.426894 32606 tablet_server_runner.cc:78] Tablet server non-default flags:
02:04:03.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:04:03.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:04:03.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:04:03.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.194
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.194:0
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:04:03.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data/info.pb
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.194
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.149.254:40723
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:04:03.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:04:03.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:04:03.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:04:03.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:04:03.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.149.194
02:04:03.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/logs
02:04:03.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 01:43:23 UTC on e7f111948823
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11951
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:03.428244 32606 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:04:03.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:03.429718 32606 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:04:03.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.445998 32612 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:03.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:03.956722 32598 heartbeater.cc:499] Master 127.31.149.254:40723 was elected leader, sending a full tablet report...
02:04:04.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:03.448951 32613 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:04.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:04.655887 32614 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
02:04:04.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:04.657738 32615 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:04.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.657799 32606 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
02:04:04.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.662439 32606 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:04:04.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.665570 32606 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:04:04.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.666954 32606 hybrid_clock.cc:648] HybridClock initialized: now 1779069844666962 us; error 86 us; skew 500 ppm
02:04:04.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.667830 32606 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:04:04.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:04 dist-test-slave-kkjj krb5kdc[32355](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069844, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.149.194@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:04:04.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.674787 32606 init.cc:377] Logged in from keytab as kudu/127.31.149.194@KRBTEST.COM (short username kudu)
02:04:04.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.680820 32606 webserver.cc:533] Webserver started at http://127.31.149.194:37451/ using document root <none> and password file <none>
02:04:04.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.681880 32606 fs_manager.cc:362] Metadata directory not provided
02:04:04.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.682099 32606 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:04:04.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.682514 32606 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:04:04.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.687388 32606 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data/instance:
02:04:04.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4ed003d38af34943866541aab9a54ee4"
02:04:04.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:04 on dist-test-slave-kkjj"
02:04:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.688686 32606 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal/instance:
02:04:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4ed003d38af34943866541aab9a54ee4"
02:04:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:04 on dist-test-slave-kkjj"
02:04:04.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.697289 32606 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.009s	sys 0.000s
02:04:04.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.703634 32623 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:04:04.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.705114 32606 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.002s
02:04:04.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.705430 32606 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data,/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4ed003d38af34943866541aab9a54ee4"
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:04 on dist-test-slave-kkjj"
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.705750 32606 fs_report.cc:389] FS layout report
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data/data
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:04:04.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:04:04.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:04:04.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.758555 32606 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:04:04.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.764463 32606 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:04:04.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.764945 32606 kserver.cc:163] Server-wide thread pool size limit: 3276
02:04:04.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.767642 32606 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
02:04:04.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.772389 32606 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
02:04:04.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.772604 32606 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
02:04:04.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.772802 32606 ts_tablet_manager.cc:616] Registered 0 tablets
02:04:04.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.772935 32606 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
02:04:04.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.920320 32606 rpc_server.cc:307] RPC server started. Bound to: 127.31.149.194:34655
02:04:04.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.920431 32735 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.149.194:34655 every 8 connection(s)
02:04:04.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.922868 32606 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/data/info.pb
02:04:04.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.930096 32343 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu as pid 32606
02:04:04.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.930677 32343 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal/instance
02:04:04.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:04 dist-test-slave-kkjj krb5kdc[32355](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069844, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.149.194@KRBTEST.COM for kudu/127.31.149.254@KRBTEST.COM
02:04:04.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.31.149.195@KRBTEST.COM; defaulting to no policy
02:04:04.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:04.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.31.149.195@KRBTEST.COM" created.
02:04:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.965804 32736 heartbeater.cc:344] Connected to a master server at 127.31.149.254:40723
02:04:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.966324 32736 heartbeater.cc:461] Registering TS with master...
02:04:04.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.967815 32736 heartbeater.cc:507] Master 127.31.149.254:40723 requested a full tablet report, sending...
02:04:04.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.970726 32405 ts_manager.cc:194] Registered new tserver with Master: 4ed003d38af34943866541aab9a54ee4 (127.31.149.194:34655)
02:04:04.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:04.972576 32405 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.31.149.194@KRBTEST.COM'} at 127.31.149.194:36077
02:04:04.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:04.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.195 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:04.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.31.149.195 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:05.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.31.149.195@KRBTEST.COM; defaulting to no policy
02:04:05.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:05.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.31.149.195@KRBTEST.COM" created.
02:04:05.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
02:04:05.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.195 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:05.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.31.149.195 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab.
02:04:05.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:05.036063 32343 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:04:05.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu
02:04:05.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/logs
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data/info.pb
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:04:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.195:0
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.149.195
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.195
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.149.254:40723
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.195
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
02:04:05.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
02:04:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:04:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:04:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:04:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:04:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
02:04:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:04:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
02:04:05.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.354764 32744 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
02:04:05.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.355501 32744 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
02:04:05.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.355901 32744 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
02:04:05.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.356688 32744 flags.cc:432] Enabled unsafe flag: --never_fsync=true
02:04:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.391419 32744 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
02:04:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.392673 32744 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.31.149.195
02:04:05.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:05.434089 32744 tablet_server_runner.cc:78] Tablet server non-default flags:
02:04:05.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
02:04:05.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.31.149.212:35325
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/kudu.keytab
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.31.149.195
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
02:04:05.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.31.149.195:0
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data/info.pb
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.31.149.195
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.31.149.254:40723
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
02:04:05.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.31.149.195
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/logs
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
02:04:05.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 01:43:23 UTC on e7f111948823
02:04:05.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11951
02:04:05.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
02:04:05.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:05.435851 32744 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
02:04:05.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:05.437412 32744 file_cache.cc:492] Constructed file cache file cache with capacity 419430
02:04:05.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.451027 32750 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:05.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:05.976238 32736 heartbeater.cc:499] Master 127.31.149.254:40723 was elected leader, sending a full tablet report...
02:04:07.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:06.855422 32749 debug-util.cc:398] Leaking SignalData structure 0x7b0800037380 after lost signal to thread 32744
02:04:07.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:07.291409 32749 kernel_stack_watchdog.cc:198] Thread 32744 stuck at /home/jenkins-slave/workspace/build_and_test_flaky@2/src/kudu/util/thread.cc:642 for 401ms:
02:04:07.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
02:04:07.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
02:04:07.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
02:04:07.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
02:04:07.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:07.292179 32744 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.841s	user 0.694s	sys 1.143s
02:04:07.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:05.452325 32751 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:07.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:07.293257 32744 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.842s	user 0.694s	sys 1.143s
02:04:07.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:07.295258 32753 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
02:04:07.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:07.297962 32752 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1840 milliseconds
02:04:07.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.298045 32744 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
02:04:07.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.299443 32744 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
02:04:07.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.301496 32744 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
02:04:07.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.302942 32744 hybrid_clock.cc:648] HybridClock initialized: now 1779069847302874 us; error 73 us; skew 500 ppm
02:04:07.304 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.304001 32744 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
02:04:07.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:07 dist-test-slave-kkjj krb5kdc[32355](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069847, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.149.195@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
02:04:07.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.311614 32744 init.cc:377] Logged in from keytab as kudu/127.31.149.195@KRBTEST.COM (short username kudu)
02:04:07.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.317031 32744 webserver.cc:533] Webserver started at http://127.31.149.195:44491/ using document root <none> and password file <none>
02:04:07.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.318275 32744 fs_manager.cc:362] Metadata directory not provided
02:04:07.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.318568 32744 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
02:04:07.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.319259 32744 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
02:04:07.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.324465 32744 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data/instance:
02:04:07.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1886be7a978646fb9e6f35ed3c5f63ef"
02:04:07.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:07 on dist-test-slave-kkjj"
02:04:07.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.325886 32744 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal/instance:
02:04:07.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1886be7a978646fb9e6f35ed3c5f63ef"
02:04:07.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:07 on dist-test-slave-kkjj"
02:04:07.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.333525 32744 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.008s	sys 0.001s
02:04:07.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.339303 32762 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
02:04:07.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.340636 32744 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.001s	sys 0.003s
02:04:07.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.340952 32744 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data,/tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal
02:04:07.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1886be7a978646fb9e6f35ed3c5f63ef"
02:04:07.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 02:04:07 on dist-test-slave-kkjj"
02:04:07.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.341313 32744 fs_report.cc:389] FS layout report
02:04:07.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
02:04:07.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal
02:04:07.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data/data
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
02:04:07.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
02:04:07.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.394501 32744 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
02:04:07.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.400125 32744 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
02:04:07.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.400534 32744 kserver.cc:163] Server-wide thread pool size limit: 3276
02:04:07.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.403224 32744 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
02:04:07.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.407214 32744 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
02:04:07.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.407411 32744 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
02:04:07.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.407631 32744 ts_tablet_manager.cc:616] Registered 0 tablets
02:04:07.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.407766 32744 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
02:04:07.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.554116 32744 rpc_server.cc:307] RPC server started. Bound to: 127.31.149.195:41675
02:04:07.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.554232   406 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.31.149.195:41675 every 8 connection(s)
02:04:07.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.556931 32744 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/data/info.pb
02:04:07.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.563832 32343 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu as pid 32744
02:04:07.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.564486 32343 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal/instance
02:04:07.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:07 dist-test-slave-kkjj krb5kdc[32355](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779069847, etypes {rep=17 tkt=17 ses=17}, kudu/127.31.149.195@KRBTEST.COM for kudu/127.31.149.254@KRBTEST.COM
02:04:07.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.594686   407 heartbeater.cc:344] Connected to a master server at 127.31.149.254:40723
02:04:07.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.595381   407 heartbeater.cc:461] Registering TS with master...
02:04:07.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.596582   407 heartbeater.cc:507] Master 127.31.149.254:40723 requested a full tablet report, sending...
02:04:07.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.599624 32405 ts_manager.cc:194] Registered new tserver with Master: 1886be7a978646fb9e6f35ed3c5f63ef (127.31.149.195:41675)
02:04:07.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.601217 32405 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.31.149.195@KRBTEST.COM'} at 127.31.149.195:41515
02:04:07.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:07.602193 32343 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
02:04:07.614 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
02:04:07.616 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

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

02:04:07.637 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.149.254"
      port: 40723
    }
    bound_http_address {
      host: "127.31.149.254"
      port: 41927
    }
  }
}

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

02:04:07.643 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.31.149.193"
      port: 45555
    }
    bound_http_address {
      host: "127.31.149.193"
      port: 46243
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.31.149.194"
      port: 34655
    }
    bound_http_address {
      host: "127.31.149.194"
      port: 37451
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.31.149.195"
      port: 41675
    }
    bound_http_address {
      host: "127.31.149.195"
      port: 44491
    }
  }
}

02:04:07.644 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
02:04:07.824 [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)
02:04:07.851 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/krb5kdc/krb5cc
02:04:07.916 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
02:04:08.054 [DEBUG - main] (Connection.java:748) [peer master-127.31.149.254:40723(127.31.149.254:40723)] connecting to peer
02:04:08.146 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.31.149.254:40723(127.31.149.254:40723)] Successfully connected to peer
02:04:08.250 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.31.149.254
02:04:08.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 02:04:08 dist-test-slave-kkjj krb5kdc[32355](info): TGS_REQ (1 etypes {17}) 127.0.0.1: ISSUE: authtime 1779069837, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.31.149.254@KRBTEST.COM
02:04:08.550 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x13c91808, L:/127.0.0.1:48918 - R:/127.31.149.254:40723] using SASL/GSSAPI
02:04:08.599 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
02:04:08.600 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=597), Traces: [0ms] refreshing cache from master, [33ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.149.254:40723, [567ms] Sub RPC ConnectToMaster: received response from server master-127.31.149.254:40723: OK, deferred=null) after lookup
02:04:08.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.604913   407 heartbeater.cc:499] Master 127.31.149.254:40723 was elected leader, sending a full tablet report...
02:04:08.622 [DEBUG - main] (Connection.java:575) [peer master-127.31.149.254:40723(127.31.149.254:40723)] disconnecting while in state READY
02:04:08.629 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.31.149.254:40723(127.31.149.254:40723)] handling channelInactive
02:04:08.630 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.31.149.254:40723(127.31.149.254:40723)] cleaning up while in state READY due to: connection closed
02:04:08.630 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.31.149.254:40723(127.31.149.254:40723) for tablet Kudu Master: connection closed
02:04:08.645 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.31.149.254:40723(127.31.149.254:40723)] connecting to peer
02:04:08.648 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.31.149.254:40723(127.31.149.254:40723)] Successfully connected to peer
02:04:08.712 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x37d3f5b6, L:/127.0.0.1:48926 - R:/127.31.149.254:40723] using TOKEN/null
02:04:08.717 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
02:04:08.717 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=94), Traces: [0ms] sending RPC to server master-127.31.149.254:40723, [7ms] received response from server master-127.31.149.254:40723: Network error: connection closed, [7ms] delaying RPC due to: Network error: connection closed, [20ms] refreshing cache from master, [21ms] Sub RPC ConnectToMaster: sending RPC to server master-127.31.149.254:40723, [93ms] Sub RPC ConnectToMaster: received response from server master-127.31.149.254:40723: OK, deferred=Deferred@1440257462(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
02:04:08.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.810249 32405 catalog_manager.cc:2282] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:48926:
02:04:08.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
02:04:08.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
02:04:08.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:04:08.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:04:08.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
02:04:08.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
02:04:08.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:08.835639 32405 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.
02:04:08.903 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 7d326840386840eca0757115549d7f9b
02:04:08.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.947472   342 tablet_service.cc:1511] Processing CreateTablet for tablet 182167da2e4f44149834e8a1f1b3256c (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=7d326840386840eca0757115549d7f9b]), partition=RANGE (key) PARTITION UNBOUNDED
02:04:08.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.949920   342 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 182167da2e4f44149834e8a1f1b3256c. 1 dirs total, 0 dirs full, 0 dirs failed
02:04:08.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.957818 32671 tablet_service.cc:1511] Processing CreateTablet for tablet 182167da2e4f44149834e8a1f1b3256c (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=7d326840386840eca0757115549d7f9b]), partition=RANGE (key) PARTITION UNBOUNDED
02:04:08.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.958693 32533 tablet_service.cc:1511] Processing CreateTablet for tablet 182167da2e4f44149834e8a1f1b3256c (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=7d326840386840eca0757115549d7f9b]), partition=RANGE (key) PARTITION UNBOUNDED
02:04:08.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.960448 32671 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 182167da2e4f44149834e8a1f1b3256c. 1 dirs total, 0 dirs full, 0 dirs failed
02:04:08.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:08.960938 32533 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 182167da2e4f44149834e8a1f1b3256c. 1 dirs total, 0 dirs full, 0 dirs failed
02:04:09.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.004926   427 tablet_bootstrap.cc:492] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Bootstrap starting.
02:04:09.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.013984   427 tablet_bootstrap.cc:654] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Neither blocks nor log segments found. Creating new log.
02:04:09.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.024140   427 log.cc:826] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Log is configured to *not* fsync() on all Append() calls
02:04:09.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.024647   428 tablet_bootstrap.cc:492] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Bootstrap starting.
02:04:09.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.040472   427 tablet_bootstrap.cc:492] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: No bootstrap required, opened a new log
02:04:09.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.040983   429 tablet_bootstrap.cc:492] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48: Bootstrap starting.
02:04:09.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.041599   427 ts_tablet_manager.cc:1403] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Time spent bootstrapping tablet: real 0.038s	user 0.014s	sys 0.008s
02:04:09.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.048926   428 tablet_bootstrap.cc:654] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Neither blocks nor log segments found. Creating new log.
02:04:09.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.049372   429 tablet_bootstrap.cc:654] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48: Neither blocks nor log segments found. Creating new log.
02:04:09.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.051741   428 log.cc:826] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Log is configured to *not* fsync() on all Append() calls
02:04:09.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.052668   429 log.cc:826] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48: Log is configured to *not* fsync() on all Append() calls
02:04:09.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.069571   428 tablet_bootstrap.cc:492] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: No bootstrap required, opened a new log
02:04:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.073087   428 ts_tablet_manager.cc:1403] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Time spent bootstrapping tablet: real 0.049s	user 0.018s	sys 0.004s
02:04:09.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.076429   427 raft_consensus.cc:359] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.078220   427 raft_consensus.cc:385] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:04:09.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.078331   429 tablet_bootstrap.cc:492] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48: No bootstrap required, opened a new log
02:04:09.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.078879   429 ts_tablet_manager.cc:1403] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48: Time spent bootstrapping tablet: real 0.039s	user 0.011s	sys 0.012s
02:04:09.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.079514   427 raft_consensus.cc:740] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1886be7a978646fb9e6f35ed3c5f63ef, State: Initialized, Role: FOLLOWER
02:04:09.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.081725   427 consensus_queue.cc:260] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [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: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.097375   427 ts_tablet_manager.cc:1434] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Time spent starting tablet: real 0.055s	user 0.036s	sys 0.005s
02:04:09.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.110780   428 raft_consensus.cc:359] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.110780   429 raft_consensus.cc:359] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.111815   429 raft_consensus.cc:385] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:04:09.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.111805   428 raft_consensus.cc:385] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
02:04:09.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.112134   429 raft_consensus.cc:740] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: e1e6eaf701ae4a1981d66f9e35358b48, State: Initialized, Role: FOLLOWER
02:04:09.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.112193   428 raft_consensus.cc:740] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 4ed003d38af34943866541aab9a54ee4, State: Initialized, Role: FOLLOWER
02:04:09.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.113070   429 consensus_queue.cc:260] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [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: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.113070   428 consensus_queue.cc:260] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [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: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.123350   428 ts_tablet_manager.cc:1434] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Time spent starting tablet: real 0.048s	user 0.039s	sys 0.004s
02:04:09.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.124457   429 ts_tablet_manager.cc:1434] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48: Time spent starting tablet: real 0.045s	user 0.032s	sys 0.007s
02:04:09.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:09.164733 32599 tablet.cc:2404] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48: Can't schedule compaction. Clean time has not been advanced past its initial value.
02:04:09.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:09.182688 32737 tablet.cc:2404] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Can't schedule compaction. Clean time has not been advanced past its initial value.
02:04:09.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:09.313423   408 tablet.cc:2404] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Can't schedule compaction. Clean time has not been advanced past its initial value.
02:04:09.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.356132   435 raft_consensus.cc:493] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
02:04:09.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.356240   434 raft_consensus.cc:493] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
02:04:09.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.356647   435 raft_consensus.cc:515] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.356814   434 raft_consensus.cc:515] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.359472   435 leader_election.cc:290] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 1886be7a978646fb9e6f35ed3c5f63ef (127.31.149.195:41675), 4ed003d38af34943866541aab9a54ee4 (127.31.149.194:34655)
02:04:09.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.359922   434 leader_election.cc:290] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers e1e6eaf701ae4a1981d66f9e35358b48 (127.31.149.193:45555), 1886be7a978646fb9e6f35ed3c5f63ef (127.31.149.195:41675)
02:04:09.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.379879 32553 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "4ed003d38af34943866541aab9a54ee4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" is_pre_election: true
02:04:09.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.380838 32553 raft_consensus.cc:2468] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 4ed003d38af34943866541aab9a54ee4 in term 0.
02:04:09.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.382652 32625 leader_election.cc:304] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [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: 4ed003d38af34943866541aab9a54ee4, e1e6eaf701ae4a1981d66f9e35358b48; no voters: 
02:04:09.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.384111   434 raft_consensus.cc:2804] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Leader pre-election won for term 1
02:04:09.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.384505   434 raft_consensus.cc:493] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
02:04:09.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.384891   434 raft_consensus.cc:3060] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 0 FOLLOWER]: Advancing to term 1
02:04:09.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.390230   362 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" is_pre_election: true
02:04:09.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.391244   362 raft_consensus.cc:2468] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate e1e6eaf701ae4a1981d66f9e35358b48 in term 0.
02:04:09.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.393268 32489 leader_election.cc:304] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [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: 1886be7a978646fb9e6f35ed3c5f63ef, e1e6eaf701ae4a1981d66f9e35358b48; no voters: 
02:04:09.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.394290   362 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "4ed003d38af34943866541aab9a54ee4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" is_pre_election: true
02:04:09.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.394737   435 raft_consensus.cc:2804] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Leader pre-election won for term 1
02:04:09.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.394639   434 raft_consensus.cc:515] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.395021   362 raft_consensus.cc:2468] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 4ed003d38af34943866541aab9a54ee4 in term 0.
02:04:09.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.395327   435 raft_consensus.cc:493] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
02:04:09.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.395679   435 raft_consensus.cc:3060] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 0 FOLLOWER]: Advancing to term 1
02:04:09.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.397672 32691 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "4ed003d38af34943866541aab9a54ee4" is_pre_election: true
02:04:09.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.398825 32691 raft_consensus.cc:2393] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 1 FOLLOWER]: Leader pre-election vote request: Denying vote to candidate e1e6eaf701ae4a1981d66f9e35358b48 in current term 1: Already voted for candidate 4ed003d38af34943866541aab9a54ee4 in this term.
02:04:09.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.399518 32553 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "4ed003d38af34943866541aab9a54ee4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "e1e6eaf701ae4a1981d66f9e35358b48"
02:04:09.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.400804   434 leader_election.cc:290] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [CANDIDATE]: Term 1 election: Requested vote from peers e1e6eaf701ae4a1981d66f9e35358b48 (127.31.149.193:45555), 1886be7a978646fb9e6f35ed3c5f63ef (127.31.149.195:41675)
02:04:09.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.402354   362 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "4ed003d38af34943866541aab9a54ee4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1886be7a978646fb9e6f35ed3c5f63ef"
02:04:09.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.402957   362 raft_consensus.cc:3060] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 0 FOLLOWER]: Advancing to term 1
02:04:09.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.402886   435 raft_consensus.cc:515] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.404203 32553 raft_consensus.cc:2393] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 1 FOLLOWER]: Leader election vote request: Denying vote to candidate 4ed003d38af34943866541aab9a54ee4 in current term 1: Already voted for candidate e1e6eaf701ae4a1981d66f9e35358b48 in this term.
02:04:09.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.405045   435 leader_election.cc:290] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [CANDIDATE]: Term 1 election: Requested vote from peers 1886be7a978646fb9e6f35ed3c5f63ef (127.31.149.195:41675), 4ed003d38af34943866541aab9a54ee4 (127.31.149.194:34655)
02:04:09.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.406047   361 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1886be7a978646fb9e6f35ed3c5f63ef"
02:04:09.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.408918 32691 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "182167da2e4f44149834e8a1f1b3256c" candidate_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "4ed003d38af34943866541aab9a54ee4"
02:04:09.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.409667 32691 raft_consensus.cc:2393] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 1 FOLLOWER]: Leader election vote request: Denying vote to candidate e1e6eaf701ae4a1981d66f9e35358b48 in current term 1: Already voted for candidate 4ed003d38af34943866541aab9a54ee4 in this term.
02:04:09.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.410780 32487 leader_election.cc:304] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [CANDIDATE]: Term 1 election: Election decided. Result: candidate lost. Election summary: received 3 responses out of 3 voters: 1 yes votes; 2 no votes. yes voters: e1e6eaf701ae4a1981d66f9e35358b48; no voters: 1886be7a978646fb9e6f35ed3c5f63ef, 4ed003d38af34943866541aab9a54ee4
02:04:09.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.412240   435 raft_consensus.cc:2749] T 182167da2e4f44149834e8a1f1b3256c P e1e6eaf701ae4a1981d66f9e35358b48 [term 1 FOLLOWER]: Leader election lost for term 1. Reason: could not achieve majority
02:04:09.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.412871   362 raft_consensus.cc:2468] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 4ed003d38af34943866541aab9a54ee4 in term 1.
02:04:09.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.414196 32627 leader_election.cc:304] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 3 responses out of 3 voters: 2 yes votes; 1 no votes. yes voters: 1886be7a978646fb9e6f35ed3c5f63ef, 4ed003d38af34943866541aab9a54ee4; no voters: e1e6eaf701ae4a1981d66f9e35358b48
02:04:09.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.415027   434 raft_consensus.cc:2804] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 1 FOLLOWER]: Leader election won for term 1
02:04:09.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.417408   434 raft_consensus.cc:697] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 1 LEADER]: Becoming Leader. State: Replica: 4ed003d38af34943866541aab9a54ee4, State: Running, Role: LEADER
02:04:09.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.418535   434 consensus_queue.cc:237] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [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: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } }
02:04:09.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.433306 32404 catalog_manager.cc:5696] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 reported cstate change: term changed from 0 to 1, leader changed from <none> to 4ed003d38af34943866541aab9a54ee4 (127.31.149.194). New cstate: current_term: 1 leader_uuid: "4ed003d38af34943866541aab9a54ee4" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "4ed003d38af34943866541aab9a54ee4" member_type: VOTER last_known_addr { host: "127.31.149.194" port: 34655 } health_report { overall_health: HEALTHY } } }
02:04:09.482 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 7d326840386840eca0757115549d7f9b
02:04:09.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.895241   434 consensus_queue.cc:1048] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [LEADER]: Connected to new peer: Peer: permanent_uuid: "1886be7a978646fb9e6f35ed3c5f63ef" member_type: VOTER last_known_addr { host: "127.31.149.195" port: 41675 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.001s
02:04:09.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:09.912807   434 consensus_queue.cc:1048] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [LEADER]: Connected to new peer: Peer: permanent_uuid: "e1e6eaf701ae4a1981d66f9e35358b48" member_type: VOTER last_known_addr { host: "127.31.149.193" port: 45555 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
02:04:10.488 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 7d326840386840eca0757115549d7f9b
02:04:10.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:10.514195 32594 debug-util.cc:398] Leaking SignalData structure 0x7b080003e0c0 after lost signal to thread 32469
02:04:10.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:10.515417 32594 debug-util.cc:398] Leaking SignalData structure 0x7b080003e9c0 after lost signal to thread 32597
02:04:10.520 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 182167da2e4f44149834e8a1f1b3256c for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
02:04:10.534 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299987, tablet=182167da2e4f44149834e8a1f1b3256c@[1886be7a978646fb9e6f35ed3c5f63ef(127.31.149.195:41675),4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)[L],e1e6eaf701ae4a1981d66f9e35358b48(127.31.149.193:45555)]}]
02:04:10.562 [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=33), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.31.149.254:40723, [18ms] Sub RPC GetTableLocations: received response from server master-127.31.149.254:40723: OK, deferred=null)) after lookup
02:04:10.580 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] connecting to peer
02:04:10.592 [DEBUG - kudu-nio-2] (Connection.java:757) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] Successfully connected to peer
02:04:10.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:10.676555   455 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:36174: token expired
02:04:10.684 [DEBUG - kudu-nio-2] (Negotiator.java:352) peer /127.31.149.194:34655 sent connection negotiation error: Not authorized: token expired
02:04:10.686 [DEBUG - kudu-nio-2] (Connection.java:239) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] handling channelInactive
02:04:10.686 [DEBUG - kudu-nio-2] (Connection.java:709) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
02:04:10.688 [DEBUG - kudu-nio-2] (Connection.java:748) [peer master-127.31.149.254:40723(127.31.149.254:40723)] connecting to peer
02:04:10.691 [DEBUG - kudu-nio-3] (Connection.java:757) [peer master-127.31.149.254:40723(127.31.149.254:40723)] Successfully connected to peer
02:04:10.702 [DEBUG - kudu-nio-3] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.31.149.254
02:04:10.760 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0xb589cfed, L:/127.0.0.1:48938 - R:/127.31.149.254:40723] using SASL/GSSAPI
02:04:10.764 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
02:04:10.765 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] connecting to peer
02:04:10.767 [DEBUG - kudu-nio-4] (Connection.java:757) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] Successfully connected to peer
02:04:10.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:10.797938   455 fault_injection.cc:43] FAULT INJECTION ENABLED!
02:04:10.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:10.798223   455 fault_injection.cc:44] THIS SERVER MAY CRASH!
02:04:10.800 [DEBUG - kudu-nio-4] (Negotiator.java:919) Authenticated connection [id: 0x3cff9e53, L:/127.0.0.1:36186 - R:/127.31.149.194:34655] using TOKEN/null
02:04:10.860 [DEBUG - kudu-nio-4] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=182167da2e4f44149834e8a1f1b3256c, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
02:04:10.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.875950 32404 catalog_manager.cc:2532] Servicing SoftDeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:48938:
02:04:10.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
02:04:10.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.876579 32404 catalog_manager.cc:2780] Servicing DeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:48938:
02:04:10.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
02:04:10.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.896433 32404 catalog_manager.cc:5983] T 00000000000000000000000000000000 P d4db7becbf884f8dae110e75fe3cafe2: Sending DeleteTablet for 3 replicas of tablet 182167da2e4f44149834e8a1f1b3256c
02:04:10.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.898864   342 tablet_service.cc:1558] Processing DeleteTablet for tablet 182167da2e4f44149834e8a1f1b3256c with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 02:04:10 UTC) from {username='kudu', principal='kudu/127.31.149.254@KRBTEST.COM'} at 127.0.0.1:53500
02:04:10.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.899358 32533 tablet_service.cc:1558] Processing DeleteTablet for tablet 182167da2e4f44149834e8a1f1b3256c with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 02:04:10 UTC) from {username='kudu', principal='kudu/127.31.149.254@KRBTEST.COM'} at 127.0.0.1:48870
02:04:10.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.902827 32671 tablet_service.cc:1558] Processing DeleteTablet for tablet 182167da2e4f44149834e8a1f1b3256c with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 02:04:10 UTC) from {username='kudu', principal='kudu/127.31.149.254@KRBTEST.COM'} at 127.0.0.1:36164
02:04:10.918 [DEBUG - main] (Connection.java:575) [peer master-127.31.149.254:40723(127.31.149.254:40723)] disconnecting while in state READY
02:04:10.920 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.31.149.254:40723(127.31.149.254:40723)] handling channelInactive
02:04:10.920 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.31.149.254:40723(127.31.149.254:40723)] cleaning up while in state READY due to: connection closed
02:04:10.922 [DEBUG - main] (Connection.java:575) [peer master-127.31.149.254:40723(127.31.149.254:40723)] disconnecting while in state READY
02:04:10.922 [DEBUG - main] (Connection.java:575) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] disconnecting while in state READY
02:04:10.924 [DEBUG - kudu-nio-4] (Connection.java:239) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] handling channelInactive
02:04:10.925 [DEBUG - kudu-nio-4] (Connection.java:709) [peer 4ed003d38af34943866541aab9a54ee4(127.31.149.194:34655)] cleaning up while in state READY due to: connection closed
02:04:10.930 [DEBUG - kudu-nio-3] (AsyncKuduClient.java:2704) Releasing all remaining resources
02:04:10.939 [DEBUG - kudu-nio-3] (Connection.java:239) [peer master-127.31.149.254:40723(127.31.149.254:40723)] handling channelInactive
02:04:10.939 [DEBUG - kudu-nio-3] (Connection.java:709) [peer master-127.31.149.254:40723(127.31.149.254:40723)] cleaning up while in state READY due to: connection closed
02:04:10.941 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.940099 32343 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu with pid 32468
02:04:10.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.944054   468 tablet_replica.cc:333] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: stopping tablet replica
02:04:10.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.944909   468 raft_consensus.cc:2243] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 1 LEADER]: Raft consensus shutting down.
02:04:10.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.946221   466 tablet_replica.cc:333] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: stopping tablet replica
02:04:10.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.947028   466 raft_consensus.cc:2243] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 1 FOLLOWER]: Raft consensus shutting down.
02:04:10.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.947849   466 raft_consensus.cc:2272] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef [term 1 FOLLOWER]: Raft consensus is shut down!
02:04:10.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.948166   468 raft_consensus.cc:2272] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4 [term 1 FOLLOWER]: Raft consensus is shut down!
02:04:10.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.950855   466 ts_tablet_manager.cc:1916] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Deleting tablet data with delete state TABLET_DATA_DELETED
02:04:10.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.957863   468 ts_tablet_manager.cc:1916] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Deleting tablet data with delete state TABLET_DATA_DELETED
02:04:10.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.967129   466 ts_tablet_manager.cc:1929] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
02:04:10.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.967942   466 log.cc:1199] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Deleting WAL directory at /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-2/wal/wals/182167da2e4f44149834e8a1f1b3256c
02:04:10.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.968943   466 ts_tablet_manager.cc:1950] T 182167da2e4f44149834e8a1f1b3256c P 1886be7a978646fb9e6f35ed3c5f63ef: Deleting consensus metadata
02:04:10.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.972448 32392 catalog_manager.cc:5027] TS 1886be7a978646fb9e6f35ed3c5f63ef (127.31.149.195:41675): tablet 182167da2e4f44149834e8a1f1b3256c (table TestAuthnTokenReacquireOpen-table [id=7d326840386840eca0757115549d7f9b]) successfully deleted
02:04:10.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.973414   468 ts_tablet_manager.cc:1929] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
02:04:10.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.974041   468 log.cc:1199] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Deleting WAL directory at /tmp/dist-test-taskAESSLN/test-tmp/mini-kudu-cluster9806413335417931948/ts-1/wal/wals/182167da2e4f44149834e8a1f1b3256c
02:04:10.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.974969   468 ts_tablet_manager.cc:1950] T 182167da2e4f44149834e8a1f1b3256c P 4ed003d38af34943866541aab9a54ee4: Deleting consensus metadata
02:04:10.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.978214 32390 catalog_manager.cc:5027] TS 4ed003d38af34943866541aab9a54ee4 (127.31.149.194:34655): tablet 182167da2e4f44149834e8a1f1b3256c (table TestAuthnTokenReacquireOpen-table [id=7d326840386840eca0757115549d7f9b]) successfully deleted
02:04:10.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:10.994045 32391 connection.cc:582] server connection from 127.31.149.193:60425 recv error: Network error: recv error from unknown peer: Transport endpoint is not connected (error 107)
02:04:10.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:10.994961 32390 proxy.cc:239] Call had error, refreshing address and retrying: Network error: recv got EOF from 127.31.149.193:45555 (error 108)
02:04:10.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:10.995608 32343 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu with pid 32606
02:04:11.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 02:04:11.008366 32390 catalog_manager.cc:4754] TS e1e6eaf701ae4a1981d66f9e35358b48 (127.31.149.193:45555): DeleteTablet:TABLET_DATA_DELETED RPC failed for tablet 182167da2e4f44149834e8a1f1b3256c: Network error: Client connection negotiation failed: client connection to 127.31.149.193:45555: connect: Connection refused (error 111)
02:04:11.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:11.028947 32343 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu with pid 32744
02:04:11.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 02:04:11.058668 32343 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskAESSLN/build/tsan/bin/kudu with pid 32371
02:04:12.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T02:04:12Z chronyd exiting

Time: 18.427

OK (1 test)

