JUnit version 4.13.2
.07:59:43.386 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1779091183377
07:59:43.417 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
07:59:43.477 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
07:59:43.485 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskwVc_HV/test-tmp
07:59:43.489 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/asan/bin
07:59:43.492 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskwVc_HV/java/build/dist-test/../../../build/asan/bin/kudu, test, mini_cluster, --serialization=pb]
07:59:43.730 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386"
  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 {
  }
}

07:59:43.912 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
07:59:43.913 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

07:59:43.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:43.915743 23191 mini_oidc.cc:150] Starting JWKS server
07:59:43.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:43.918660 23191 webserver.cc:533] Webserver started at http://127.0.0.1:37373/ using document root <none> and password file <none>
07:59:43.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:43.918879 23191 mini_oidc.cc:186] Starting OIDC Discovery server
07:59:43.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:43.919870 23191 webserver.cc:533] Webserver started at http://127.0.0.1:35015/ using document root <none> and password file <none>
07:59:43.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
07:59:43.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/principal' for realm 'KRBTEST.COM',
07:59:43.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
07:59:43.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:43 dist-test-slave-1jjm krb5kdc[23201](info): setting up network...
07:59:43.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
07:59:43.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:43 dist-test-slave-1jjm krb5kdc[23201](info): set up 2 sockets
07:59:43.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:43 dist-test-slave-1jjm krb5kdc[23201](info): commencing operation
07:59:43.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
07:59:45.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:45.957626 23191 mini_kdc.cc:121] Time spent starting KDC: real 2.031s	user 0.002s	sys 0.013s
07:59:45.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
07:59:45.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
07:59:45.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
07:59:45.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
07:59:46.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
07:59:46.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
07:59:46.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
07:59:46.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
07:59:46.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
07:59:46.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
07:59:46.060 [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-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/test-user.keytab.
07:59:46.060 [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-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/test-user.keytab.
07:59:46.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:46 dist-test-slave-1jjm krb5kdc[23201](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091186, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
07:59:46.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
07:59:46.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T07:59:46Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
07:59:46.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T07:59:46Z Disabled control of system clock
07:59:46.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.22.165.254@KRBTEST.COM; defaulting to no policy
07:59:46.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.115 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.22.165.254@KRBTEST.COM" created.
07:59:46.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.254 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.254 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.22.165.254@KRBTEST.COM; defaulting to no policy
07:59:46.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.22.165.254@KRBTEST.COM" created.
07:59:46.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.254 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.254 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.172822 23191 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:46.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:46.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/wal
07:59:46.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data
07:59:46.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
07:59:46.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
07:59:46.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
07:59:46.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:46.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:46.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:46.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/logs
07:59:46.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data/info.pb
07:59:46.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:46.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
07:59:46.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
07:59:46.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
07:59:46.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
07:59:46.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:46.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:46.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:46.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
07:59:46.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
07:59:46.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
07:59:46.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
07:59:46.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.254:40505
07:59:46.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.254
07:59:46.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:46.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:46.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.254
07:59:46.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:46.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
07:59:46.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
07:59:46.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
07:59:46.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:46.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:46.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:46.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:46.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
07:59:46.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.22.165.254:40505
07:59:46.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
07:59:46.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37373/jwks.json
07:59:46.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
07:59:46.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
07:59:46.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
07:59:46.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.486105 23217 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
07:59:46.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.486415 23217 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
07:59:46.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.486512 23217 flags.cc:432] Enabled unsafe flag: --never_fsync=true
07:59:46.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.496192 23217 flags.cc:432] Enabled experimental flag: --authn_token_validity_seconds=1
07:59:46.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.496287 23217 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
07:59:46.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.496349 23217 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
07:59:46.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.496389 23217 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
07:59:46.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.496441 23217 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
07:59:46.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.496480 23217 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
07:59:46.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.496526 23217 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37373/jwks.json
07:59:46.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.510488 23217 master_runner.cc:387] Master server non-default flags:
07:59:46.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:46.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:46.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data
07:59:46.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/wal
07:59:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
07:59:46.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
07:59:46.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.22.165.254:40505
07:59:46.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:46.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.254
07:59:46.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:46.514 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:46.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
07:59:46.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.254:40505
07:59:46.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
07:59:46.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
07:59:46.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
07:59:46.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37373/jwks.json
07:59:46.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:46.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
07:59:46.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
07:59:46.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
07:59:46.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:46.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:46.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data/info.pb
07:59:46.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.254
07:59:46.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:46.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
07:59:46.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:46.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
07:59:46.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
07:59:46.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:46.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/logs
07:59:46.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:46.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
07:59:46.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
07:59:46.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
07:59:46.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
07:59:46.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
07:59:46.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
07:59:46.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:24 UTC on bdcb31816ec0
07:59:46.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11954
07:59:46.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
07:59:46.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.517889 23217 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
07:59:46.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.519862 23217 file_cache.cc:492] Constructed file cache file cache with capacity 419430
07:59:46.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.530386 23225 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
07:59:46.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.530956 23217 server_base.cc:1061] running on GCE node
07:59:46.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.530356 23223 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
07:59:46.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:46.530355 23222 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
07:59:46.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.532280 23217 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
07:59:46.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.533694 23217 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
07:59:46.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.534955 23217 hybrid_clock.cc:648] HybridClock initialized: now 1779091186534873 us; error 46 us; skew 500 ppm
07:59:46.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.535388 23217 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
07:59:46.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:46 dist-test-slave-1jjm krb5kdc[23201](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091186, etypes {rep=17 tkt=17 ses=17}, kudu/127.22.165.254@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
07:59:46.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.538636 23217 init.cc:377] Logged in from keytab as kudu/127.22.165.254@KRBTEST.COM (short username kudu)
07:59:46.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.540252 23217 webserver.cc:533] Webserver started at http://127.22.165.254:38415/ using document root <none> and password file <none>
07:59:46.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.541049 23217 fs_manager.cc:362] Metadata directory not provided
07:59:46.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.541178 23217 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
07:59:46.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.541622 23217 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
07:59:46.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.544102 23217 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data/instance:
07:59:46.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2"
07:59:46.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:46 on dist-test-slave-1jjm"
07:59:46.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.545012 23217 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/wal/instance:
07:59:46.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2"
07:59:46.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:46 on dist-test-slave-1jjm"
07:59:46.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.550573 23217 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.006s	sys 0.000s
07:59:46.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.554108 23232 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
07:59:46.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.555594 23217 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.002s
07:59:46.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.555827 23217 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data,/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/wal
07:59:46.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2"
07:59:46.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:46 on dist-test-slave-1jjm"
07:59:46.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.556067 23217 fs_report.cc:389] FS layout report
07:59:46.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
07:59:46.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/wal
07:59:46.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/wal
07:59:46.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data/data
07:59:46.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
07:59:46.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
07:59:46.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
07:59:46.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
07:59:46.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
07:59:46.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
07:59:46.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
07:59:46.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
07:59:46.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
07:59:46.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
07:59:46.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
07:59:46.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.596733 23217 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
07:59:46.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.601617 23217 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
07:59:46.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.601933 23217 kserver.cc:163] Server-wide thread pool size limit: 3276
07:59:46.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.624385 23217 rpc_server.cc:307] RPC server started. Bound to: 127.22.165.254:40505
07:59:46.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.624361 23283 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.22.165.254:40505 every 8 connection(s)
07:59:46.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.626122 23217 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/data/info.pb
07:59:46.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.630581 23191 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu as pid 23217
07:59:46.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.630884 23191 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/master-0/wal/instance
07:59:46.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.631058 23284 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
07:59:46.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.646510 23284 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2: Bootstrap starting.
07:59:46.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.653739 23284 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2: Neither blocks nor log segments found. Creating new log.
07:59:46.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.655951 23284 log.cc:826] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2: Log is configured to *not* fsync() on all Append() calls
07:59:46.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.660274 23284 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2: No bootstrap required, opened a new log
07:59:46.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.668746 23284 raft_consensus.cc:359] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2" member_type: VOTER last_known_addr { host: "127.22.165.254" port: 40505 } }
07:59:46.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.669342 23284 raft_consensus.cc:385] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
07:59:46.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.669462 23284 raft_consensus.cc:740] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d86e02a92c8b4b1ca4aa6db56636e8a2, State: Initialized, Role: FOLLOWER
07:59:46.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.670536 23284 consensus_queue.cc:260] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [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: "d86e02a92c8b4b1ca4aa6db56636e8a2" member_type: VOTER last_known_addr { host: "127.22.165.254" port: 40505 } }
07:59:46.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.671657 23284 raft_consensus.cc:399] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
07:59:46.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.671860 23284 raft_consensus.cc:493] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
07:59:46.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.672050 23284 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 0 FOLLOWER]: Advancing to term 1
07:59:46.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.674785 23284 raft_consensus.cc:515] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2" member_type: VOTER last_known_addr { host: "127.22.165.254" port: 40505 } }
07:59:46.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:46 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091186, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.22.165.254@KRBTEST.COM
07:59:46.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.675434 23284 leader_election.cc:304] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [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: d86e02a92c8b4b1ca4aa6db56636e8a2; no voters: 
07:59:46.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.676223 23284 leader_election.cc:290] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [CANDIDATE]: Term 1 election: Requested vote from peers 
07:59:46.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.676342 23289 raft_consensus.cc:2804] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 1 FOLLOWER]: Leader election won for term 1
07:59:46.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.678287 23289 raft_consensus.cc:697] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [term 1 LEADER]: Becoming Leader. State: Replica: d86e02a92c8b4b1ca4aa6db56636e8a2, State: Running, Role: LEADER
07:59:46.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.679219 23289 consensus_queue.cc:237] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [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: "d86e02a92c8b4b1ca4aa6db56636e8a2" member_type: VOTER last_known_addr { host: "127.22.165.254" port: 40505 } }
07:59:46.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.688318 23284 sys_catalog.cc:565] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [sys.catalog]: configured and running, proceeding with master startup.
07:59:46.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.688830 23290 sys_catalog.cc:455] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [sys.catalog]: SysCatalogTable state changed. Reason: New leader d86e02a92c8b4b1ca4aa6db56636e8a2. Latest consensus state: current_term: 1 leader_uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2" member_type: VOTER last_known_addr { host: "127.22.165.254" port: 40505 } } }
07:59:46.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.689157 23290 sys_catalog.cc:458] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [sys.catalog]: This master's current role is: LEADER
07:59:46.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.688369 23291 sys_catalog.cc:455] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "d86e02a92c8b4b1ca4aa6db56636e8a2" member_type: VOTER last_known_addr { host: "127.22.165.254" port: 40505 } } }
07:59:46.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.689889 23291 sys_catalog.cc:458] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2 [sys.catalog]: This master's current role is: LEADER
07:59:46.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.695565 23295 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
07:59:46.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.703176 23295 catalog_manager.cc:1519] Initializing Kudu cluster ID...
07:59:46.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.713353 23295 catalog_manager.cc:1382] Generated new cluster ID: 2eb8197add624cd68e18c4293475bd1f
07:59:46.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.713490 23295 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
07:59:46.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.726627 23295 catalog_manager.cc:1405] Generated new certificate authority record
07:59:46.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.728355 23295 catalog_manager.cc:1539] Loading token signing keys...
07:59:46.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.744803 23295 catalog_manager.cc:6069] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2: Generated new TSK 0
07:59:46.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.745847 23295 catalog_manager.cc:1549] Initializing in-progress tserver states...
07:59:46.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.22.165.193@KRBTEST.COM; defaulting to no policy
07:59:46.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.22.165.193@KRBTEST.COM" created.
07:59:46.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.193 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.193 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.22.165.193@KRBTEST.COM; defaulting to no policy
07:59:46.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.22.165.193@KRBTEST.COM" created.
07:59:46.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:46.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.193 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.193 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:46.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:46.836580 23191 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:46.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:46.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal
07:59:46.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data
07:59:46.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
07:59:46.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
07:59:46.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
07:59:46.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:46.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:46.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:46.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/logs
07:59:46.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data/info.pb
07:59:46.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:46.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
07:59:46.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
07:59:46.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
07:59:46.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
07:59:46.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:46.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:46.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:46.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
07:59:46.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
07:59:46.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.193:0
07:59:46.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.22.165.193
07:59:46.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.193
07:59:46.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:46.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.22.165.254:40505
07:59:46.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:46.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.193
07:59:46.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:46.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
07:59:46.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
07:59:46.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
07:59:46.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:46.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:46.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:46.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:46.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
07:59:46.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
07:59:46.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
07:59:47.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.165578 23313 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
07:59:47.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.165944 23313 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
07:59:47.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.166011 23313 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
07:59:47.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.166162 23313 flags.cc:432] Enabled unsafe flag: --never_fsync=true
07:59:47.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.174933 23313 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
07:59:47.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.175226 23313 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.22.165.193
07:59:47.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.185856 23313 tablet_server_runner.cc:78] Tablet server non-default flags:
07:59:47.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:47.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:47.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:47.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:47.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data
07:59:47.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal
07:59:47.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
07:59:47.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:47.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.193
07:59:47.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:47.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:47.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.193:0
07:59:47.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
07:59:47.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:47.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
07:59:47.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
07:59:47.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
07:59:47.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:47.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:47.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data/info.pb
07:59:47.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.193
07:59:47.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:47.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.22.165.254:40505
07:59:47.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
07:59:47.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:47.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
07:59:47.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
07:59:47.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:47.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.22.165.193
07:59:47.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/logs
07:59:47.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:47.191 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
07:59:47.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
07:59:47.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
07:59:47.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
07:59:47.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
07:59:47.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
07:59:47.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:24 UTC on bdcb31816ec0
07:59:47.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11954
07:59:47.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
07:59:47.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.187959 23313 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
07:59:47.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.189723 23313 file_cache.cc:492] Constructed file cache file cache with capacity 419430
07:59:47.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.204321 23319 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
07:59:47.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.204910 23318 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
07:59:47.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.207422 23321 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
07:59:47.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.207427 23313 server_base.cc:1061] running on GCE node
07:59:47.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.208189 23313 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
07:59:47.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.209026 23313 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
07:59:47.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.210294 23313 hybrid_clock.cc:648] HybridClock initialized: now 1779091187210237 us; error 30 us; skew 500 ppm
07:59:47.210 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.210668 23313 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
07:59:47.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:47 dist-test-slave-1jjm krb5kdc[23201](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091187, etypes {rep=17 tkt=17 ses=17}, kudu/127.22.165.193@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
07:59:47.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.213960 23313 init.cc:377] Logged in from keytab as kudu/127.22.165.193@KRBTEST.COM (short username kudu)
07:59:47.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.215521 23313 webserver.cc:533] Webserver started at http://127.22.165.193:35243/ using document root <none> and password file <none>
07:59:47.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.216308 23313 fs_manager.cc:362] Metadata directory not provided
07:59:47.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.216442 23313 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
07:59:47.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.216881 23313 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
07:59:47.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.219251 23313 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data/instance:
07:59:47.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "606e8abe1fe845dba497fa9290957b90"
07:59:47.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:47 on dist-test-slave-1jjm"
07:59:47.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.220232 23313 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal/instance:
07:59:47.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "606e8abe1fe845dba497fa9290957b90"
07:59:47.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:47 on dist-test-slave-1jjm"
07:59:47.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.226083 23313 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.005s	sys 0.000s
07:59:47.229 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.229370 23328 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
07:59:47.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.230722 23313 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.000s
07:59:47.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.230918 23313 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data,/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal
07:59:47.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "606e8abe1fe845dba497fa9290957b90"
07:59:47.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:47 on dist-test-slave-1jjm"
07:59:47.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.231150 23313 fs_report.cc:389] FS layout report
07:59:47.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
07:59:47.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal
07:59:47.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal
07:59:47.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data/data
07:59:47.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
07:59:47.232 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
07:59:47.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
07:59:47.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
07:59:47.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.246244 23313 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
07:59:47.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.249907 23313 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
07:59:47.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.250126 23313 kserver.cc:163] Server-wide thread pool size limit: 3276
07:59:47.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.251088 23313 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
07:59:47.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.252578 23313 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
07:59:47.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.252664 23313 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
07:59:47.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.252736 23313 ts_tablet_manager.cc:616] Registered 0 tablets
07:59:47.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.252821 23313 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
07:59:47.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.286247 23313 rpc_server.cc:307] RPC server started. Bound to: 127.22.165.193:36617
07:59:47.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.286335 23440 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.22.165.193:36617 every 8 connection(s)
07:59:47.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.287567 23313 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/data/info.pb
07:59:47.296 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.296567 23191 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu as pid 23313
07:59:47.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.296784 23191 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal/instance
07:59:47.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:47 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091187, etypes {rep=17 tkt=17 ses=17}, kudu/127.22.165.193@KRBTEST.COM for kudu/127.22.165.254@KRBTEST.COM
07:59:47.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.311705 23441 heartbeater.cc:344] Connected to a master server at 127.22.165.254:40505
07:59:47.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.312163 23441 heartbeater.cc:461] Registering TS with master...
07:59:47.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.313390 23441 heartbeater.cc:507] Master 127.22.165.254:40505 requested a full tablet report, sending...
07:59:47.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.22.165.194@KRBTEST.COM; defaulting to no policy
07:59:47.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.315992 23249 ts_manager.cc:194] Registered new tserver with Master: 606e8abe1fe845dba497fa9290957b90 (127.22.165.193:36617)
07:59:47.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.317644 23249 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.22.165.193@KRBTEST.COM'} at 127.22.165.193:50187
07:59:47.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.22.165.194@KRBTEST.COM" created.
07:59:47.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.194 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.194 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.22.165.194@KRBTEST.COM; defaulting to no policy
07:59:47.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.22.165.194@KRBTEST.COM" created.
07:59:47.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.194 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.194 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.375298 23191 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:47.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:47.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal
07:59:47.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/logs
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data/info.pb
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.194:0
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.22.165.194
07:59:47.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.194
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.22.165.254:40505
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.194
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:47.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:47.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:47.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
07:59:47.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
07:59:47.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
07:59:47.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.692317 23449 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
07:59:47.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.692651 23449 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
07:59:47.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.692699 23449 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
07:59:47.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.692804 23449 flags.cc:432] Enabled unsafe flag: --never_fsync=true
07:59:47.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.701377 23449 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
07:59:47.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.701572 23449 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.22.165.194
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.712038 23449 tablet_server_runner.cc:78] Tablet server non-default flags:
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.194
07:59:47.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.194:0
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data/info.pb
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.194
07:59:47.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.22.165.254:40505
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.22.165.194
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/logs
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
07:59:47.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:24 UTC on bdcb31816ec0
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11954
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.713523 23449 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
07:59:47.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.715035 23449 file_cache.cc:492] Constructed file cache file cache with capacity 419430
07:59:47.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.725584 23454 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
07:59:47.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.725787 23455 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
07:59:47.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:47.727102 23457 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
07:59:47.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.728376 23449 server_base.cc:1061] running on GCE node
07:59:47.729 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.729010 23449 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
07:59:47.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.730074 23449 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
07:59:47.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.731333 23449 hybrid_clock.cc:648] HybridClock initialized: now 1779091187731212 us; error 92 us; skew 500 ppm
07:59:47.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.731698 23449 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
07:59:47.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:47 dist-test-slave-1jjm krb5kdc[23201](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091187, etypes {rep=17 tkt=17 ses=17}, kudu/127.22.165.194@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
07:59:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.734894 23449 init.cc:377] Logged in from keytab as kudu/127.22.165.194@KRBTEST.COM (short username kudu)
07:59:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.736397 23449 webserver.cc:533] Webserver started at http://127.22.165.194:41197/ using document root <none> and password file <none>
07:59:47.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.737095 23449 fs_manager.cc:362] Metadata directory not provided
07:59:47.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.737205 23449 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
07:59:47.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.737599 23449 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
07:59:47.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.740546 23449 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data/instance:
07:59:47.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6"
07:59:47.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:47 on dist-test-slave-1jjm"
07:59:47.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.741346 23449 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal/instance:
07:59:47.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6"
07:59:47.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:47 on dist-test-slave-1jjm"
07:59:47.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.746469 23449 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.006s	sys 0.000s
07:59:47.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.749642 23464 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
07:59:47.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.750947 23449 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.000s
07:59:47.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.751080 23449 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data,/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal
07:59:47.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6"
07:59:47.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:47 on dist-test-slave-1jjm"
07:59:47.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.751304 23449 fs_report.cc:389] FS layout report
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data/data
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
07:59:47.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
07:59:47.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.770385 23449 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
07:59:47.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.774348 23449 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
07:59:47.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.774571 23449 kserver.cc:163] Server-wide thread pool size limit: 3276
07:59:47.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.775480 23449 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
07:59:47.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.777585 23449 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
07:59:47.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.777678 23449 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
07:59:47.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.777750 23449 ts_tablet_manager.cc:616] Registered 0 tablets
07:59:47.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.777798 23449 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
07:59:47.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.813715 23449 rpc_server.cc:307] RPC server started. Bound to: 127.22.165.194:33315
07:59:47.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.813766 23576 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.22.165.194:33315 every 8 connection(s)
07:59:47.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.815109 23449 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/data/info.pb
07:59:47.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.822266 23191 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu as pid 23449
07:59:47.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.822449 23191 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal/instance
07:59:47.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:47 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091187, etypes {rep=17 tkt=17 ses=17}, kudu/127.22.165.194@KRBTEST.COM for kudu/127.22.165.254@KRBTEST.COM
07:59:47.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.837069 23577 heartbeater.cc:344] Connected to a master server at 127.22.165.254:40505
07:59:47.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.837459 23577 heartbeater.cc:461] Registering TS with master...
07:59:47.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.838279 23577 heartbeater.cc:507] Master 127.22.165.254:40505 requested a full tablet report, sending...
07:59:47.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.22.165.195@KRBTEST.COM; defaulting to no policy
07:59:47.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.839808 23249 ts_manager.cc:194] Registered new tserver with Master: 9563c1bd3e824cfc9c07ee1cea3e1cc6 (127.22.165.194:33315)
07:59:47.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.840629 23249 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.22.165.194@KRBTEST.COM'} at 127.22.165.194:54731
07:59:47.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.22.165.195@KRBTEST.COM" created.
07:59:47.862 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.863 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.195 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.863 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.22.165.195 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for HTTP/127.22.165.195@KRBTEST.COM; defaulting to no policy
07:59:47.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "HTTP/127.22.165.195@KRBTEST.COM" created.
07:59:47.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
07:59:47.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.195 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal HTTP/127.22.165.195 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab.
07:59:47.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:47.900312 23191 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:47.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/logs
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data/info.pb
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
07:59:47.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.195:0
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.22.165.195
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.195
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:47.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.22.165.254:40505
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.195
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=true
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:47.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:47.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_require_spnego=false
07:59:47.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
07:59:47.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
07:59:48.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.233852 23585 flags.cc:432] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
07:59:48.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.234190 23585 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
07:59:48.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.234239 23585 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
07:59:48.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.234342 23585 flags.cc:432] Enabled unsafe flag: --never_fsync=true
07:59:48.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.242831 23585 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
07:59:48.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.243017 23585 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.22.165.195
07:59:48.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.253589 23585 tablet_server_runner.cc:78] Tablet server non-default flags:
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.22.165.212:36893
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kudu.keytab
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.22.165.195
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.22.165.195:0
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
07:59:48.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data/info.pb
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.22.165.195
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.22.165.254:40505
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
07:59:48.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.22.165.195
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/logs
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 07:43:24 UTC on bdcb31816ec0
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11954
07:59:48.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
07:59:48.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.255082 23585 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
07:59:48.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.256647 23585 file_cache.cc:492] Constructed file cache file cache with capacity 419430
07:59:48.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.265587 23590 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
07:59:48.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.266400 23591 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
07:59:48.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:48.267745 23593 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
07:59:48.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.267719 23585 server_base.cc:1061] running on GCE node
07:59:48.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.268502 23585 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
07:59:48.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.269402 23585 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
07:59:48.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.270664 23585 hybrid_clock.cc:648] HybridClock initialized: now 1779091188270603 us; error 35 us; skew 500 ppm
07:59:48.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.271060 23585 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
07:59:48.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:48 dist-test-slave-1jjm krb5kdc[23201](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091188, etypes {rep=17 tkt=17 ses=17}, kudu/127.22.165.195@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
07:59:48.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.274633 23585 init.cc:377] Logged in from keytab as kudu/127.22.165.195@KRBTEST.COM (short username kudu)
07:59:48.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.276185 23585 webserver.cc:533] Webserver started at http://127.22.165.195:42041/ using document root <none> and password file <none>
07:59:48.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.276975 23585 fs_manager.cc:362] Metadata directory not provided
07:59:48.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.277115 23585 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
07:59:48.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.277535 23585 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
07:59:48.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.280061 23585 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data/instance:
07:59:48.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b38a23bd2e92413cbf967c00722a4595"
07:59:48.280 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:48 on dist-test-slave-1jjm"
07:59:48.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.280967 23585 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal/instance:
07:59:48.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b38a23bd2e92413cbf967c00722a4595"
07:59:48.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:48 on dist-test-slave-1jjm"
07:59:48.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.286530 23585 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.005s	sys 0.001s
07:59:48.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.290825 23600 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.292339 23585 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.292492 23585 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data,/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b38a23bd2e92413cbf967c00722a4595"
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 07:59:48 on dist-test-slave-1jjm"
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.292658 23585 fs_report.cc:389] FS layout report
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal
07:59:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data/data
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
07:59:48.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
07:59:48.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.321432 23441 heartbeater.cc:499] Master 127.22.165.254:40505 was elected leader, sending a full tablet report...
07:59:48.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.330024 23585 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
07:59:48.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.333870 23585 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
07:59:48.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.334090 23585 kserver.cc:163] Server-wide thread pool size limit: 3276
07:59:48.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.334935 23585 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
07:59:48.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.336455 23585 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
07:59:48.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.336575 23585 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
07:59:48.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.336678 23585 ts_tablet_manager.cc:616] Registered 0 tablets
07:59:48.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.336755 23585 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
07:59:48.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.373768 23585 rpc_server.cc:307] RPC server started. Bound to: 127.22.165.195:38335
07:59:48.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.373860 23712 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.22.165.195:38335 every 8 connection(s)
07:59:48.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.375381 23585 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/data/info.pb
07:59:48.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.380791 23191 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu as pid 23585
07:59:48.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.380951 23191 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal/instance
07:59:48.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:48 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091188, etypes {rep=17 tkt=17 ses=17}, kudu/127.22.165.195@KRBTEST.COM for kudu/127.22.165.254@KRBTEST.COM
07:59:48.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.395074 23713 heartbeater.cc:344] Connected to a master server at 127.22.165.254:40505
07:59:48.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.395423 23713 heartbeater.cc:461] Registering TS with master...
07:59:48.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.396268 23713 heartbeater.cc:507] Master 127.22.165.254:40505 requested a full tablet report, sending...
07:59:48.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.397898 23249 ts_manager.cc:194] Registered new tserver with Master: b38a23bd2e92413cbf967c00722a4595 (127.22.165.195:38335)
07:59:48.398 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.398798 23249 master_service.cc:502] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.22.165.195@KRBTEST.COM'} at 127.22.165.195:53629
07:59:48.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.409372 23191 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
07:59:48.411 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
07:59:48.412 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

07:59:48.420 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

07:59:48.420 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

07:59:48.428 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.22.165.254"
      port: 40505
    }
    bound_http_address {
      host: "127.22.165.254"
      port: 38415
    }
  }
}

07:59:48.431 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

07:59:48.433 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.22.165.193"
      port: 36617
    }
    bound_http_address {
      host: "127.22.165.193"
      port: 35243
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.22.165.194"
      port: 33315
    }
    bound_http_address {
      host: "127.22.165.194"
      port: 41197
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.22.165.195"
      port: 38335
    }
    bound_http_address {
      host: "127.22.165.195"
      port: 42041
    }
  }
}

07:59:48.433 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
07:59:48.644 [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)
07:59:48.667 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/krb5kdc/krb5cc
07:59:48.718 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
07:59:48.838 [DEBUG - main] (Connection.java:748) [peer master-127.22.165.254:40505(127.22.165.254:40505)] connecting to peer
07:59:48.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:48.843463 23577 heartbeater.cc:499] Master 127.22.165.254:40505 was elected leader, sending a full tablet report...
07:59:48.910 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.22.165.254:40505(127.22.165.254:40505)] Successfully connected to peer
07:59:48.997 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.22.165.254
07:59:49.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:49 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (1 etypes {17}) 127.0.0.1: ISSUE: authtime 1779091186, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.22.165.254@KRBTEST.COM
07:59:49.244 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x26a92d61, L:/127.0.0.1:49350 - R:/127.22.165.254:40505] using SASL/GSSAPI
07:59:49.280 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
07:59:49.281 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=483), Traces: [0ms] refreshing cache from master, [24ms] Sub RPC ConnectToMaster: sending RPC to server master-127.22.165.254:40505, [458ms] Sub RPC ConnectToMaster: received response from server master-127.22.165.254:40505: OK, deferred=null) after lookup
07:59:49.298 [DEBUG - main] (Connection.java:575) [peer master-127.22.165.254:40505(127.22.165.254:40505)] disconnecting while in state READY
07:59:49.304 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.22.165.254:40505(127.22.165.254:40505)] handling channelInactive
07:59:49.305 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.22.165.254:40505(127.22.165.254:40505)] cleaning up while in state READY due to: connection closed
07:59:49.306 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.22.165.254:40505(127.22.165.254:40505) for tablet Kudu Master: connection closed
07:59:49.319 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.22.165.254:40505(127.22.165.254:40505)] connecting to peer
07:59:49.321 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.22.165.254:40505(127.22.165.254:40505)] Successfully connected to peer
07:59:49.380 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xfe10d069, L:/127.0.0.1:49362 - R:/127.22.165.254:40505] using TOKEN/null
07:59:49.384 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
07:59:49.384 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=84), Traces: [0ms] sending RPC to server master-127.22.165.254:40505, [6ms] received response from server master-127.22.165.254:40505: Network error: connection closed, [7ms] delaying RPC due to: Network error: connection closed, [19ms] refreshing cache from master, [20ms] Sub RPC ConnectToMaster: sending RPC to server master-127.22.165.254:40505, [84ms] Sub RPC ConnectToMaster: received response from server master-127.22.165.254:40505: OK, deferred=Deferred@1926904033(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
07:59:49.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.401439 23713 heartbeater.cc:499] Master 127.22.165.254:40505 was elected leader, sending a full tablet report...
07:59:49.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.449914 23249 catalog_manager.cc:2282] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:49362:
07:59:49.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
07:59:49.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
07:59:49.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
07:59:49.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
07:59:49.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
07:59:49.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
07:59:49.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
07:59:49.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
07:59:49.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
07:59:49.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:49.456615 23249 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.
07:59:49.498 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table ce8d720a09844a42975b3e773eef880c
07:59:49.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.508478 23376 tablet_service.cc:1511] Processing CreateTablet for tablet 2282657bf12347faa9c1ee658ed1e3b2 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=ce8d720a09844a42975b3e773eef880c]), partition=RANGE (key) PARTITION UNBOUNDED
07:59:49.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.508615 23648 tablet_service.cc:1511] Processing CreateTablet for tablet 2282657bf12347faa9c1ee658ed1e3b2 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=ce8d720a09844a42975b3e773eef880c]), partition=RANGE (key) PARTITION UNBOUNDED
07:59:49.510 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.510632 23376 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 2282657bf12347faa9c1ee658ed1e3b2. 1 dirs total, 0 dirs full, 0 dirs failed
07:59:49.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.510632 23648 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 2282657bf12347faa9c1ee658ed1e3b2. 1 dirs total, 0 dirs full, 0 dirs failed
07:59:49.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.510815 23512 tablet_service.cc:1511] Processing CreateTablet for tablet 2282657bf12347faa9c1ee658ed1e3b2 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=ce8d720a09844a42975b3e773eef880c]), partition=RANGE (key) PARTITION UNBOUNDED
07:59:49.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.512208 23512 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 2282657bf12347faa9c1ee658ed1e3b2. 1 dirs total, 0 dirs full, 0 dirs failed
07:59:49.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.538650 23733 tablet_bootstrap.cc:492] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Bootstrap starting.
07:59:49.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.542102 23732 tablet_bootstrap.cc:492] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Bootstrap starting.
07:59:49.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.545374 23733 tablet_bootstrap.cc:654] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Neither blocks nor log segments found. Creating new log.
07:59:49.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.545989 23734 tablet_bootstrap.cc:492] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Bootstrap starting.
07:59:49.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.547012 23733 log.cc:826] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Log is configured to *not* fsync() on all Append() calls
07:59:49.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.550143 23733 tablet_bootstrap.cc:492] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: No bootstrap required, opened a new log
07:59:49.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.550453 23733 ts_tablet_manager.cc:1403] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Time spent bootstrapping tablet: real 0.015s	user 0.007s	sys 0.000s
07:59:49.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.550501 23734 tablet_bootstrap.cc:654] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Neither blocks nor log segments found. Creating new log.
07:59:49.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.551800 23734 log.cc:826] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Log is configured to *not* fsync() on all Append() calls
07:59:49.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.552656 23732 tablet_bootstrap.cc:654] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Neither blocks nor log segments found. Creating new log.
07:59:49.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.554427 23732 log.cc:826] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Log is configured to *not* fsync() on all Append() calls
07:59:49.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.557911 23733 raft_consensus.cc:359] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.558542 23733 raft_consensus.cc:385] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
07:59:49.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.558791 23733 raft_consensus.cc:740] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: b38a23bd2e92413cbf967c00722a4595, State: Initialized, Role: FOLLOWER
07:59:49.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.559710 23733 consensus_queue.cc:260] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [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: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.565793 23733 ts_tablet_manager.cc:1434] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Time spent starting tablet: real 0.015s	user 0.009s	sys 0.003s
07:59:49.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.566609 23732 tablet_bootstrap.cc:492] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: No bootstrap required, opened a new log
07:59:49.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.566615 23734 tablet_bootstrap.cc:492] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: No bootstrap required, opened a new log
07:59:49.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.566926 23734 ts_tablet_manager.cc:1403] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Time spent bootstrapping tablet: real 0.021s	user 0.010s	sys 0.005s
07:59:49.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.567345 23732 ts_tablet_manager.cc:1403] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Time spent bootstrapping tablet: real 0.026s	user 0.007s	sys 0.007s
07:59:49.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.573952 23734 raft_consensus.cc:359] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.574250 23732 raft_consensus.cc:359] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.574596 23732 raft_consensus.cc:385] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
07:59:49.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.574687 23732 raft_consensus.cc:740] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 606e8abe1fe845dba497fa9290957b90, State: Initialized, Role: FOLLOWER
07:59:49.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.575170 23734 raft_consensus.cc:385] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
07:59:49.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.575284 23734 raft_consensus.cc:740] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 9563c1bd3e824cfc9c07ee1cea3e1cc6, State: Initialized, Role: FOLLOWER
07:59:49.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.575320 23732 consensus_queue.cc:260] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [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: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.576004 23734 consensus_queue.cc:260] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [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: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.577060 23732 ts_tablet_manager.cc:1434] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Time spent starting tablet: real 0.009s	user 0.006s	sys 0.003s
07:59:49.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.578724 23734 ts_tablet_manager.cc:1434] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Time spent starting tablet: real 0.012s	user 0.012s	sys 0.000s
07:59:49.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:49.628260 23714 tablet.cc:2404] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Can't schedule compaction. Clean time has not been advanced past its initial value.
07:59:49.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.643357 23740 raft_consensus.cc:493] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
07:59:49.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.643641 23740 raft_consensus.cc:515] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.645567 23740 leader_election.cc:290] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 606e8abe1fe845dba497fa9290957b90 (127.22.165.193:36617), b38a23bd2e92413cbf967c00722a4595 (127.22.165.195:38335)
07:59:49.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.657088 23396 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "2282657bf12347faa9c1ee658ed1e3b2" candidate_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "606e8abe1fe845dba497fa9290957b90" is_pre_election: true
07:59:49.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.657497 23668 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "2282657bf12347faa9c1ee658ed1e3b2" candidate_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b38a23bd2e92413cbf967c00722a4595" is_pre_election: true
07:59:49.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.657714 23396 raft_consensus.cc:2468] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 9563c1bd3e824cfc9c07ee1cea3e1cc6 in term 0.
07:59:49.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.657923 23668 raft_consensus.cc:2468] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 9563c1bd3e824cfc9c07ee1cea3e1cc6 in term 0.
07:59:49.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.658653 23466 leader_election.cc:304] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [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: 9563c1bd3e824cfc9c07ee1cea3e1cc6, b38a23bd2e92413cbf967c00722a4595; no voters: 
07:59:49.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.659544 23740 raft_consensus.cc:2804] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Leader pre-election won for term 1
07:59:49.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.659741 23740 raft_consensus.cc:493] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
07:59:49.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.659848 23740 raft_consensus.cc:3060] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 0 FOLLOWER]: Advancing to term 1
07:59:49.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.662534 23740 raft_consensus.cc:515] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.663218 23740 leader_election.cc:290] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [CANDIDATE]: Term 1 election: Requested vote from peers 606e8abe1fe845dba497fa9290957b90 (127.22.165.193:36617), b38a23bd2e92413cbf967c00722a4595 (127.22.165.195:38335)
07:59:49.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.663798 23668 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "2282657bf12347faa9c1ee658ed1e3b2" candidate_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b38a23bd2e92413cbf967c00722a4595"
07:59:49.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.664005 23668 raft_consensus.cc:3060] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 0 FOLLOWER]: Advancing to term 1
07:59:49.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.667047 23396 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "2282657bf12347faa9c1ee658ed1e3b2" candidate_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "606e8abe1fe845dba497fa9290957b90"
07:59:49.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.667095 23668 raft_consensus.cc:2468] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 9563c1bd3e824cfc9c07ee1cea3e1cc6 in term 1.
07:59:49.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.667263 23396 raft_consensus.cc:3060] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 0 FOLLOWER]: Advancing to term 1
07:59:49.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.667554 23466 leader_election.cc:304] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [CANDIDATE]: Term 1 election: Election decided. Result: candidate won. Election summary: received 2 responses out of 3 voters: 2 yes votes; 0 no votes. yes voters: 9563c1bd3e824cfc9c07ee1cea3e1cc6, b38a23bd2e92413cbf967c00722a4595; no voters: 
07:59:49.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.667945 23740 raft_consensus.cc:2804] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 1 FOLLOWER]: Leader election won for term 1
07:59:49.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.670917 23396 raft_consensus.cc:2468] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 9563c1bd3e824cfc9c07ee1cea3e1cc6 in term 1.
07:59:49.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.671478 23740 raft_consensus.cc:697] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 1 LEADER]: Becoming Leader. State: Replica: 9563c1bd3e824cfc9c07ee1cea3e1cc6, State: Running, Role: LEADER
07:59:49.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.672165 23740 consensus_queue.cc:237] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [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: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } }
07:59:49.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:49.676834 23249 catalog_manager.cc:5696] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 reported cstate change: term changed from 0 to 1, leader changed from <none> to 9563c1bd3e824cfc9c07ee1cea3e1cc6 (127.22.165.194). New cstate: current_term: 1 leader_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "9563c1bd3e824cfc9c07ee1cea3e1cc6" member_type: VOTER last_known_addr { host: "127.22.165.194" port: 33315 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 } health_report { overall_health: UNKNOWN } } }
07:59:49.769 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table ce8d720a09844a42975b3e773eef880c
07:59:49.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:49.794260 23442 tablet.cc:2404] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Can't schedule compaction. Clean time has not been advanced past its initial value.
07:59:49.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:49.822136 23578 tablet.cc:2404] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Can't schedule compaction. Clean time has not been advanced past its initial value.
07:59:50.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:50.081192 23740 consensus_queue.cc:1048] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [LEADER]: Connected to new peer: Peer: permanent_uuid: "b38a23bd2e92413cbf967c00722a4595" member_type: VOTER last_known_addr { host: "127.22.165.195" port: 38335 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
07:59:50.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:50.088675 23740 consensus_queue.cc:1048] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [LEADER]: Connected to new peer: Peer: permanent_uuid: "606e8abe1fe845dba497fa9290957b90" member_type: VOTER last_known_addr { host: "127.22.165.193" port: 36617 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
07:59:50.773 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table ce8d720a09844a42975b3e773eef880c
07:59:50.795 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 2282657bf12347faa9c1ee658ed1e3b2 for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
07:59:50.801 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=2282657bf12347faa9c1ee658ed1e3b2@[606e8abe1fe845dba497fa9290957b90(127.22.165.193:36617),9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)[L],b38a23bd2e92413cbf967c00722a4595(127.22.165.195:38335)]}]
07:59:50.808 [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=14), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.22.165.254:40505, [8ms] Sub RPC GetTableLocations: received response from server master-127.22.165.254:40505: OK, deferred=null)) after lookup
07:59:50.818 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] connecting to peer
07:59:50.821 [DEBUG - kudu-nio-2] (Connection.java:757) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] Successfully connected to peer
07:59:50.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:50.868369 23754 fault_injection.cc:43] FAULT INJECTION ENABLED!
07:59:50.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:50.868482 23754 fault_injection.cc:44] THIS SERVER MAY CRASH!
07:59:50.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:50.869167 23754 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:51292: token signing key expired
07:59:50.874 [DEBUG - kudu-nio-2] (Negotiator.java:352) peer /127.22.165.194:33315 sent connection negotiation error: Not authorized: token signing key expired
07:59:50.875 [DEBUG - kudu-nio-2] (Connection.java:239) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] handling channelInactive
07:59:50.875 [DEBUG - kudu-nio-2] (Connection.java:709) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
07:59:50.877 [DEBUG - kudu-nio-2] (Connection.java:748) [peer master-127.22.165.254:40505(127.22.165.254:40505)] connecting to peer
07:59:50.880 [DEBUG - kudu-nio-3] (Connection.java:757) [peer master-127.22.165.254:40505(127.22.165.254:40505)] Successfully connected to peer
07:59:50.892 [DEBUG - kudu-nio-3] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.22.165.254
07:59:50.929 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x5b6b034a, L:/127.0.0.1:49368 - R:/127.22.165.254:40505] using SASL/GSSAPI
07:59:50.932 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
07:59:50.933 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] connecting to peer
07:59:50.934 [DEBUG - kudu-nio-4] (Connection.java:757) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] Successfully connected to peer
07:59:50.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 07:59:50.956542 23754 negotiation.cc:343] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:51298: invalid token signature
07:59:50.958 [DEBUG - kudu-nio-4] (Negotiator.java:352) peer /127.22.165.194:33315 sent connection negotiation error: Not authorized: invalid token signature
07:59:50.960 [DEBUG - kudu-nio-4] (Connection.java:239) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] handling channelInactive
07:59:50.960 [DEBUG - kudu-nio-4] (Connection.java:709) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
07:59:50.963 [INFO - kudu-nio-3] (AsyncKuduClient.java:516) connect to master: received a new authn token
07:59:50.964 [DEBUG - kudu-nio-3] (Connection.java:748) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] connecting to peer
07:59:50.966 [DEBUG - kudu-nio-5] (Connection.java:757) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] Successfully connected to peer
07:59:51.004 [DEBUG - kudu-nio-5] (Negotiator.java:919) Authenticated connection [id: 0x4aefde37, L:/127.0.0.1:51300 - R:/127.22.165.194:33315] using TOKEN/null
07:59:51.033 [DEBUG - kudu-nio-5] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=2282657bf12347faa9c1ee658ed1e3b2, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
07:59:51.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.045964 23248 catalog_manager.cc:2532] Servicing SoftDeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:49368:
07:59:51.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
07:59:51.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.046259 23248 catalog_manager.cc:2780] Servicing DeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:49368:
07:59:51.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
07:59:51.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.051404 23248 catalog_manager.cc:5983] T 00000000000000000000000000000000 P d86e02a92c8b4b1ca4aa6db56636e8a2: Sending DeleteTablet for 3 replicas of tablet 2282657bf12347faa9c1ee658ed1e3b2
07:59:51.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.052968 23376 tablet_service.cc:1558] Processing DeleteTablet for tablet 2282657bf12347faa9c1ee658ed1e3b2 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 07:59:51 UTC) from {username='kudu', principal='kudu/127.22.165.254@KRBTEST.COM'} at 127.0.0.1:34656
07:59:51.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.052968 23648 tablet_service.cc:1558] Processing DeleteTablet for tablet 2282657bf12347faa9c1ee658ed1e3b2 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 07:59:51 UTC) from {username='kudu', principal='kudu/127.22.165.254@KRBTEST.COM'} at 127.0.0.1:53572
07:59:51.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.056573 23512 tablet_service.cc:1558] Processing DeleteTablet for tablet 2282657bf12347faa9c1ee658ed1e3b2 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-05-18 07:59:51 UTC) from {username='kudu', principal='kudu/127.22.165.254@KRBTEST.COM'} at 127.0.0.1:51280
07:59:51.066 [DEBUG - main] (Connection.java:575) [peer master-127.22.165.254:40505(127.22.165.254:40505)] disconnecting while in state READY
07:59:51.067 [DEBUG - main] (Connection.java:575) [peer master-127.22.165.254:40505(127.22.165.254:40505)] disconnecting while in state READY
07:59:51.068 [DEBUG - main] (Connection.java:575) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] disconnecting while in state READY
07:59:51.070 [DEBUG - kudu-nio-5] (Connection.java:239) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] handling channelInactive
07:59:51.070 [DEBUG - kudu-nio-5] (Connection.java:709) [peer 9563c1bd3e824cfc9c07ee1cea3e1cc6(127.22.165.194:33315)] cleaning up while in state READY due to: connection closed
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.067134 23767 tablet_replica.cc:333] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: stopping tablet replica
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.067641 23767 raft_consensus.cc:2243] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 1 LEADER]: Raft consensus shutting down.
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.068372 23767 raft_consensus.cc:2272] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6 [term 1 FOLLOWER]: Raft consensus is shut down!
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.069401 23766 tablet_replica.cc:333] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: stopping tablet replica
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.070271 23765 tablet_replica.cc:333] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: stopping tablet replica
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.070338 23767 ts_tablet_manager.cc:1916] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Deleting tablet data with delete state TABLET_DATA_DELETED
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.070649 23765 raft_consensus.cc:2243] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 1 FOLLOWER]: Raft consensus shutting down.
07:59:51.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.071004 23765 raft_consensus.cc:2272] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90 [term 1 FOLLOWER]: Raft consensus is shut down!
07:59:51.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.069802 23766 raft_consensus.cc:2243] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 1 FOLLOWER]: Raft consensus shutting down.
07:59:51.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.071512 23766 raft_consensus.cc:2272] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595 [term 1 FOLLOWER]: Raft consensus is shut down!
07:59:51.072 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.22.165.254:40505(127.22.165.254:40505)] handling channelInactive
07:59:51.073 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.22.165.254:40505(127.22.165.254:40505)] cleaning up while in state READY due to: connection closed
07:59:51.073 [DEBUG - kudu-nio-3] (AsyncKuduClient.java:2704) Releasing all remaining resources
07:59:51.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.075600 23766 ts_tablet_manager.cc:1916] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Deleting tablet data with delete state TABLET_DATA_DELETED
07:59:51.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.075944 23765 ts_tablet_manager.cc:1916] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Deleting tablet data with delete state TABLET_DATA_DELETED
07:59:51.084 [DEBUG - kudu-nio-3] (Connection.java:239) [peer master-127.22.165.254:40505(127.22.165.254:40505)] handling channelInactive
07:59:51.084 [DEBUG - kudu-nio-3] (Connection.java:709) [peer master-127.22.165.254:40505(127.22.165.254:40505)] cleaning up while in state READY due to: connection closed
07:59:51.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.085373 23191 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu pid 23313
07:59:51.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.088378 23765 ts_tablet_manager.cc:1929] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
07:59:51.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.089972 23766 ts_tablet_manager.cc:1929] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
07:59:51.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.093181 23767 ts_tablet_manager.cc:1929] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
07:59:51.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.093516 23766 log.cc:1199] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Deleting WAL directory at /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-2/wal/wals/2282657bf12347faa9c1ee658ed1e3b2
07:59:51.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.094172 23766 ts_tablet_manager.cc:1950] T 2282657bf12347faa9c1ee658ed1e3b2 P b38a23bd2e92413cbf967c00722a4595: Deleting consensus metadata
07:59:51.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.094463 23767 log.cc:1199] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Deleting WAL directory at /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-1/wal/wals/2282657bf12347faa9c1ee658ed1e3b2
07:59:51.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.095165 23767 ts_tablet_manager.cc:1950] T 2282657bf12347faa9c1ee658ed1e3b2 P 9563c1bd3e824cfc9c07ee1cea3e1cc6: Deleting consensus metadata
07:59:51.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.095448 23765 log.cc:1199] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Deleting WAL directory at /tmp/dist-test-taskwVc_HV/test-tmp/mini-kudu-cluster16488698339457098386/ts-0/wal/wals/2282657bf12347faa9c1ee658ed1e3b2
07:59:51.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.098968 23765 ts_tablet_manager.cc:1950] T 2282657bf12347faa9c1ee658ed1e3b2 P 606e8abe1fe845dba497fa9290957b90: Deleting consensus metadata
07:59:51.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.102790 23233 catalog_manager.cc:5027] TS 606e8abe1fe845dba497fa9290957b90 (127.22.165.193:36617): tablet 2282657bf12347faa9c1ee658ed1e3b2 (table TestAuthnTokenReacquireOpen-table [id=ce8d720a09844a42975b3e773eef880c]) successfully deleted
07:59:51.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.104255 23234 catalog_manager.cc:5027] TS b38a23bd2e92413cbf967c00722a4595 (127.22.165.195:38335): tablet 2282657bf12347faa9c1ee658ed1e3b2 (table TestAuthnTokenReacquireOpen-table [id=ce8d720a09844a42975b3e773eef880c]) successfully deleted
07:59:51.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.104945 23233 catalog_manager.cc:5027] TS 9563c1bd3e824cfc9c07ee1cea3e1cc6 (127.22.165.194:33315): tablet 2282657bf12347faa9c1ee658ed1e3b2 (table TestAuthnTokenReacquireOpen-table [id=ce8d720a09844a42975b3e773eef880c]) successfully deleted
07:59:51.116 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:51 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091186, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.22.165.193@KRBTEST.COM
07:59:51.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.132272 23436 generic_service.cc:196] Checking for leaks (request via RPC)
07:59:51.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.224633 23191 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu with pid 23313
07:59:51.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.241701 23191 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu pid 23449
07:59:51.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:51 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091186, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.22.165.194@KRBTEST.COM
07:59:51.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.258912 23572 generic_service.cc:196] Checking for leaks (request via RPC)
07:59:51.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.377467 23191 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu with pid 23449
07:59:51.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.400032 23191 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu pid 23585
07:59:51.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) May 18 07:59:51 dist-test-slave-1jjm krb5kdc[23201](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1779091186, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.22.165.195@KRBTEST.COM
07:59:51.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.414965 23708 generic_service.cc:196] Checking for leaks (request via RPC)
07:59:51.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.506786 23191 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu with pid 23585
07:59:51.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.523748 23191 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu pid 23217
07:59:51.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.524856 23279 generic_service.cc:196] Checking for leaks (request via RPC)
07:59:51.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 07:59:51.634694 23191 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskwVc_HV/build/asan/bin/kudu with pid 23217
07:59:51.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T07:59:51Z chronyd exiting

Time: 8.626

OK (1 test)

