JUnit version 4.13.2
.05:22:33.355 [INFO - main] (RandomUtils.java:49) Using random seed: 1783488153350
05:22:33.372 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
05:22:33.431 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
05:22:33.442 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskzXBHjk/test-tmp
05:22:33.445 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
05:22:33.449 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskzXBHjk/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
05:22:33.677 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726"
  extra_master_flags: "--authn_token_validity_seconds=1"
  extra_master_flags: "--rpc_default_keepalive_time_ms=120000"
  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 {
  }
}

05:22:33.825 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
05:22:33.826 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

05:22:33.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.830013 17305 mini_oidc.cc:152] Starting JWKS server
05:22:33.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.833554 17305 webserver.cc:460] Webserver started at http://127.0.0.1:34167/ using document root <none> and password file <none>
05:22:33.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.833964 17305 mini_oidc.cc:189] Starting OIDC Discovery server
05:22:33.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:33.835784 17305 webserver.cc:460] Webserver started at http://127.0.0.1:39261/ using document root <none> and password file <none>
05:22:33.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
05:22:33.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/principal' for realm 'KRBTEST.COM',
05:22:33.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
05:22:33.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:33 dist-test-slave-1738 krb5kdc[17316](info): setting up network...
05:22:33.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
05:22:33.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:33 dist-test-slave-1738 krb5kdc[17316](info): set up 2 sockets
05:22:33.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:33 dist-test-slave-1738 krb5kdc[17316](info): commencing operation
05:22:33.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
05:22:34.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:34.930817 17305 mini_kdc.cc:121] Time spent starting KDC: real 1.069s	user 0.009s	sys 0.011s
05:22:34.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
05:22:34.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:34.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
05:22:34.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
05:22:34.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:34.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
05:22:35.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
05:22:35.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:35.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
05:22:35.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
05:22:35.036 [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-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/test-user.keytab.
05:22:35.036 [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-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/test-user.keytab.
05:22:35.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:35 dist-test-slave-1738 krb5kdc[17316](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488155, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:35.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
05:22:35.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:35Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
05:22:35.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:35Z Disabled control of system clock
05:22:35.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.16.230.126@KRBTEST.COM; defaulting to no policy
05:22:35.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:35.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.16.230.126@KRBTEST.COM" created.
05:22:35.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:35.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.126 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.126.keytab.
05:22:35.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.126 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.126.keytab.
05:22:35.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.127041 17305 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:35.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:35.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/wal
05:22:35.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data
05:22:35.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:35.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:35.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:35.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:35.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/logs
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data/info.pb
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:35.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.126:39619
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.126
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.126.keytab
05:22:35.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.126
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.230.126:39619
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
05:22:35.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:34167/jwks.json
05:22:35.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
05:22:35.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:35.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.449738 17329 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:35.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.450428 17329 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:35.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.451215 17329 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:35.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.483053 17329 flags.cc:425] Enabled experimental flag: --authn_token_validity_seconds=1
05:22:35.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.483355 17329 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
05:22:35.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.483850 17329 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:35.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.484287 17329 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
05:22:35.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.484616 17329 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
05:22:35.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.484982 17329 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
05:22:35.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.485247 17329 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:34167/jwks.json
05:22:35.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.520507 17329 master_runner.cc:386] Master server non-default flags:
05:22:35.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:35.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:35.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:35.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:35.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/wal
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.16.230.126:39619
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.126.keytab
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.126
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
05:22:35.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.126:39619
05:22:35.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
05:22:35.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:35.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
05:22:35.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:34167/jwks.json
05:22:35.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:35.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
05:22:35.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:35.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:35.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:35.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:35.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data/info.pb
05:22:35.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.126
05:22:35.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:35.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:35.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:35.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:35.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/logs
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:35.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:35.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:35.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:35.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:35.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.522003 17329 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:35.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.523427 17329 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:35.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.543706 17335 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
05:22:36.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.543823 17338 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
05:22:36.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:35.543744 17336 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
05:22:36.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:35.545413 17329 server_base.cc:1034] running on GCE node
05:22:36.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.805004 17329 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:36.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.807471 17329 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:36.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.808910 17329 hybrid_clock.cc:648] HybridClock initialized: now 1783488156808745 us; error 77 us; skew 500 ppm
05:22:36.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.809698 17329 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:36.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:36 dist-test-slave-1738 krb5kdc[17316](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488156, etypes {rep=17 tkt=17 ses=17}, kudu/127.16.230.126@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:36.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.815764 17329 init.cc:377] Logged in from keytab as kudu/127.16.230.126@KRBTEST.COM (short username kudu)
05:22:36.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.821588 17329 webserver.cc:460] Webserver started at http://127.16.230.126:39149/ using document root <none> and password file <none>
05:22:36.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.822539 17329 fs_manager.cc:359] Metadata directory not provided
05:22:36.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.822772 17329 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:36.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.823313 17329 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:36.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.828099 17329 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data/instance:
05:22:36.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a106b55762a640c4954254d493098a97"
05:22:36.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:36 on dist-test-slave-1738"
05:22:36.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.829490 17329 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/wal/instance:
05:22:36.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a106b55762a640c4954254d493098a97"
05:22:36.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:36 on dist-test-slave-1738"
05:22:36.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.836668 17329 fs_manager.cc:691] Time spent creating directory manager: real 0.006s	user 0.006s	sys 0.000s
05:22:36.842 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.842424 17346 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:36.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.843590 17329 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.004s
05:22:36.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.843888 17329 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data,/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/wal
05:22:36.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a106b55762a640c4954254d493098a97"
05:22:36.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:36 on dist-test-slave-1738"
05:22:36.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.844182 17329 fs_report.cc:383] FS layout report
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/wal
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/wal
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data/data
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:36.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:36.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:36.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:36.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:36.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.890597 17329 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:36.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.896601 17329 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
05:22:36.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.897182 17329 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:36.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.969193 17329 rpc_server.cc:307] RPC server started. Bound to: 127.16.230.126:39619
05:22:36.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.969283 17397 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.16.230.126:39619 every 8 connection(s)
05:22:36.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.971766 17329 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/data/info.pb
05:22:36.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.975121 17305 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu as pid 17329
05:22:36.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.975698 17305 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/master-0/wal/instance
05:22:36.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:36.978176 17398 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
05:22:37.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.000142 17398 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97: Bootstrap starting.
05:22:37.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.005816 17398 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97: Neither blocks nor log segments found. Creating new log.
05:22:37.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.008111 17398 log.cc:824] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97: Log is configured to *not* fsync() on all Append() calls
05:22:37.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.014022 17398 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97: No bootstrap required, opened a new log
05:22:37.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:37 dist-test-slave-1738 krb5kdc[17316](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488155, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.16.230.126@KRBTEST.COM
05:22:37.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.038798 17398 raft_consensus.cc:348] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a106b55762a640c4954254d493098a97" member_type: VOTER last_known_addr { host: "127.16.230.126" port: 39619 } }
05:22:37.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.039546 17398 raft_consensus.cc:374] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
05:22:37.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.039783 17398 raft_consensus.cc:729] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a106b55762a640c4954254d493098a97, State: Initialized, Role: FOLLOWER
05:22:37.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.040505 17398 consensus_queue.cc:260] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [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: "a106b55762a640c4954254d493098a97" member_type: VOTER last_known_addr { host: "127.16.230.126" port: 39619 } }
05:22:37.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.041213 17398 raft_consensus.cc:388] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
05:22:37.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.041576 17398 raft_consensus.cc:482] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
05:22:37.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.041911 17398 raft_consensus.cc:3037] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 0 FOLLOWER]: Advancing to term 1
05:22:37.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.046290 17398 raft_consensus.cc:504] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a106b55762a640c4954254d493098a97" member_type: VOTER last_known_addr { host: "127.16.230.126" port: 39619 } }
05:22:37.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.047124 17398 leader_election.cc:302] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [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: a106b55762a640c4954254d493098a97; no voters: 
05:22:37.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.049266 17398 leader_election.cc:288] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [CANDIDATE]: Term 1 election: Requested vote from peers 
05:22:37.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.049623 17403 raft_consensus.cc:2781] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 1 FOLLOWER]: Leader election won for term 1
05:22:37.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.052380 17403 raft_consensus.cc:686] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [term 1 LEADER]: Becoming Leader. State: Replica: a106b55762a640c4954254d493098a97, State: Running, Role: LEADER
05:22:37.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.053272 17403 consensus_queue.cc:237] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [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: "a106b55762a640c4954254d493098a97" member_type: VOTER last_known_addr { host: "127.16.230.126" port: 39619 } }
05:22:37.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.054272 17398 sys_catalog.cc:564] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [sys.catalog]: configured and running, proceeding with master startup.
05:22:37.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.064515 17405 sys_catalog.cc:455] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [sys.catalog]: SysCatalogTable state changed. Reason: New leader a106b55762a640c4954254d493098a97. Latest consensus state: current_term: 1 leader_uuid: "a106b55762a640c4954254d493098a97" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a106b55762a640c4954254d493098a97" member_type: VOTER last_known_addr { host: "127.16.230.126" port: 39619 } } }
05:22:37.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.066054 17405 sys_catalog.cc:458] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [sys.catalog]: This master's current role is: LEADER
05:22:37.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.068157 17404 sys_catalog.cc:455] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "a106b55762a640c4954254d493098a97" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "a106b55762a640c4954254d493098a97" member_type: VOTER last_known_addr { host: "127.16.230.126" port: 39619 } } }
05:22:37.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.069937 17410 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
05:22:37.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.069885 17404 sys_catalog.cc:458] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97 [sys.catalog]: This master's current role is: LEADER
05:22:37.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.079411 17410 catalog_manager.cc:1484] Initializing Kudu cluster ID...
05:22:37.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.097493 17410 catalog_manager.cc:1347] Generated new cluster ID: a19643a016514d228c64291a9adb0cf6
05:22:37.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.097819 17410 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
05:22:37.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.118351 17410 catalog_manager.cc:1370] Generated new certificate authority record
05:22:37.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.120193 17410 catalog_manager.cc:1504] Loading token signing keys...
05:22:37.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.131558 17410 catalog_manager.cc:5868] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97: Generated new TSK 0
05:22:37.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.132863 17410 catalog_manager.cc:1514] Initializing in-progress tserver states...
05:22:37.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.16.230.65@KRBTEST.COM; defaulting to no policy
05:22:37.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:37.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.16.230.65@KRBTEST.COM" created.
05:22:37.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:37.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.65 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.65.keytab.
05:22:37.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.65 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.65.keytab.
05:22:37.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.202803 17305 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:37.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:37.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/wal
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/logs
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data/info.pb
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:37.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:37.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:37.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:37.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.65:0
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.230.65
05:22:37.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.65
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.230.126:39619
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.65.keytab
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.65
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:37.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:37.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:37.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:37.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
05:22:37.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:37.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.516645 17424 flags.cc:425] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
05:22:37.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.517396 17424 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:37.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.517721 17424 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:37.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.518491 17424 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:37.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.551054 17424 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:37.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.552208 17424 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.16.230.65
05:22:37.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.588277 17424 tablet_server_runner.cc:78] Tablet server non-default flags:
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/wal
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.65.keytab
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.65
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:37.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.65:0
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data/info.pb
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.65
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.230.126:39619
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:37.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.230.65
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/logs
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:37.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:37.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.589738 17424 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:37.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.591161 17424 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:37.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.624343 17433 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
05:22:38.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.624653 17430 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
05:22:38.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:37.626070 17431 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
05:22:38.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:37.627123 17424 server_base.cc:1034] running on GCE node
05:22:38.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.874773 17424 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:38.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.878022 17424 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:38.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.879510 17424 hybrid_clock.cc:648] HybridClock initialized: now 1783488158879437 us; error 88 us; skew 500 ppm
05:22:38.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.880645 17424 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:38.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:38 dist-test-slave-1738 krb5kdc[17316](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488158, etypes {rep=17 tkt=17 ses=17}, kudu/127.16.230.65@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:38.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.889218 17424 init.cc:377] Logged in from keytab as kudu/127.16.230.65@KRBTEST.COM (short username kudu)
05:22:38.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.895824 17424 webserver.cc:460] Webserver started at http://127.16.230.65:39075/ using document root <none> and password file <none>
05:22:38.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.896724 17424 fs_manager.cc:359] Metadata directory not provided
05:22:38.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.896960 17424 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:38.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.897339 17424 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:38.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.902364 17424 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data/instance:
05:22:38.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d1b36b751b54ced93b59a74b8eaffed"
05:22:38.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:38 on dist-test-slave-1738"
05:22:38.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.903406 17424 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/wal/instance:
05:22:38.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d1b36b751b54ced93b59a74b8eaffed"
05:22:38.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:38 on dist-test-slave-1738"
05:22:38.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.910871 17424 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.008s	sys 0.000s
05:22:38.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.916751 17441 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:38.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.918286 17424 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.001s	sys 0.002s
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.918617 17424 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data,/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/wal
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0d1b36b751b54ced93b59a74b8eaffed"
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:38 on dist-test-slave-1738"
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.918938 17424 fs_report.cc:383] FS layout report
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/wal
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/wal
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data/data
05:22:38.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:38.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:38.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.967914 17424 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:38.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.974591 17424 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
05:22:38.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.975121 17424 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:38.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.978348 17424 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
05:22:38.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.983019 17424 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
05:22:38.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.983244 17424 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
05:22:38.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.983469 17424 ts_tablet_manager.cc:610] Registered 0 tablets
05:22:38.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:38.983598 17424 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
05:22:39.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.134941 17424 rpc_server.cc:307] RPC server started. Bound to: 127.16.230.65:41211
05:22:39.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.135074 17553 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.16.230.65:41211 every 8 connection(s)
05:22:39.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.138039 17424 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/data/info.pb
05:22:39.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.139932 17305 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu as pid 17424
05:22:39.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.140412 17305 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-0/wal/instance
05:22:39.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.16.230.66@KRBTEST.COM; defaulting to no policy
05:22:39.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:39.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.16.230.66@KRBTEST.COM" created.
05:22:39.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:39 dist-test-slave-1738 krb5kdc[17316](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488158, etypes {rep=17 tkt=17 ses=17}, kudu/127.16.230.65@KRBTEST.COM for kudu/127.16.230.126@KRBTEST.COM
05:22:39.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.187552 17554 heartbeater.cc:347] Connected to a master server at 127.16.230.126:39619
05:22:39.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.188105 17554 heartbeater.cc:464] Registering TS with master...
05:22:39.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.189422 17554 heartbeater.cc:511] Master 127.16.230.126:39619 requested a full tablet report, sending...
05:22:39.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.192669 17363 ts_manager.cc:194] Registered new tserver with Master: 0d1b36b751b54ced93b59a74b8eaffed (127.16.230.65:41211)
05:22:39.195 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.195281 17363 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.16.230.65@KRBTEST.COM'} at 127.16.230.65:37439
05:22:39.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.66 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.66.keytab.
05:22:39.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.66 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.66.keytab.
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.203622 17305 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:39.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/logs
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data/info.pb
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.66:0
05:22:39.205 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.230.66
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.66
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.230.126:39619
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.66.keytab
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.66
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:39.206 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:39.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:39.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:39.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:39.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
05:22:39.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:39.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.522421 17560 flags.cc:425] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
05:22:39.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.523145 17560 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:39.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.523339 17560 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:39.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.523911 17560 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:39.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.555250 17560 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:39.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.556341 17560 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.16.230.66
05:22:39.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.595337 17560 tablet_server_runner.cc:78] Tablet server non-default flags:
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.66.keytab
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.66
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.66:0
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data/info.pb
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.66
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.230.126:39619
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:39.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.230.66
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/logs
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.596499 17560 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:39.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.597461 17560 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:39.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.617985 17567 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
05:22:40.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.200699 17554 heartbeater.cc:503] Master 127.16.230.126:39619 was elected leader, sending a full tablet report...
05:22:40.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.617976 17569 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
05:22:40.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:39.621546 17560 server_base.cc:1034] running on GCE node
05:22:40.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:39.618745 17566 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
05:22:40.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.886570 17560 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:40.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.891599 17560 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:40.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.893378 17560 hybrid_clock.cc:648] HybridClock initialized: now 1783488160893333 us; error 53 us; skew 500 ppm
05:22:40.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.894363 17560 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:40.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:40 dist-test-slave-1738 krb5kdc[17316](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488160, etypes {rep=17 tkt=17 ses=17}, kudu/127.16.230.66@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:40.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.900789 17560 init.cc:377] Logged in from keytab as kudu/127.16.230.66@KRBTEST.COM (short username kudu)
05:22:40.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.907385 17560 webserver.cc:460] Webserver started at http://127.16.230.66:33689/ using document root <none> and password file <none>
05:22:40.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.908563 17560 fs_manager.cc:359] Metadata directory not provided
05:22:40.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.908792 17560 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:40.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.909312 17560 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:40.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.914633 17560 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data/instance:
05:22:40.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1005733e2ad942729748e67d949c6fdf"
05:22:40.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:40 on dist-test-slave-1738"
05:22:40.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.915810 17560 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal/instance:
05:22:40.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1005733e2ad942729748e67d949c6fdf"
05:22:40.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:40 on dist-test-slave-1738"
05:22:40.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.923862 17560 fs_manager.cc:691] Time spent creating directory manager: real 0.007s	user 0.002s	sys 0.008s
05:22:40.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.929850 17577 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:40.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.931089 17560 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.000s	sys 0.005s
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.931510 17560 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data,/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "1005733e2ad942729748e67d949c6fdf"
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:40 on dist-test-slave-1738"
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.932008 17560 fs_report.cc:383] FS layout report
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data/data
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:40.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:40.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:40.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:40.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:40.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:40.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.977901 17560 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:40.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.984709 17560 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
05:22:40.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.985316 17560 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:40.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.988279 17560 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
05:22:40.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.992735 17560 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
05:22:40.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.993042 17560 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.001s	sys 0.000s
05:22:40.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.993299 17560 ts_tablet_manager.cc:610] Registered 0 tablets
05:22:40.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:40.993476 17560 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
05:22:41.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.141721 17560 rpc_server.cc:307] RPC server started. Bound to: 127.16.230.66:44319
05:22:41.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.141788 17689 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.16.230.66:44319 every 8 connection(s)
05:22:41.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.146077 17560 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/data/info.pb
05:22:41.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.156672 17305 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu as pid 17560
05:22:41.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.157171 17305 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal/instance
05:22:41.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:41 dist-test-slave-1738 krb5kdc[17316](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488160, etypes {rep=17 tkt=17 ses=17}, kudu/127.16.230.66@KRBTEST.COM for kudu/127.16.230.126@KRBTEST.COM
05:22:41.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.16.230.67@KRBTEST.COM; defaulting to no policy
05:22:41.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:41.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.16.230.67@KRBTEST.COM" created.
05:22:41.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.192345 17690 heartbeater.cc:347] Connected to a master server at 127.16.230.126:39619
05:22:41.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.192720 17690 heartbeater.cc:464] Registering TS with master...
05:22:41.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.193672 17690 heartbeater.cc:511] Master 127.16.230.126:39619 requested a full tablet report, sending...
05:22:41.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.195853 17363 ts_manager.cc:194] Registered new tserver with Master: 1005733e2ad942729748e67d949c6fdf (127.16.230.66:44319)
05:22:41.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.197567 17363 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.16.230.66@KRBTEST.COM'} at 127.16.230.66:33203
05:22:41.208 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
05:22:41.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.67 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.67.keytab.
05:22:41.209 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.16.230.67 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.67.keytab.
05:22:41.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.211319 17305 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:41.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/logs
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data/info.pb
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.67:0
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.230.67
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.67
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.230.126:39619
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.67.keytab
05:22:41.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.67
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
05:22:41.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
05:22:41.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.516755 17696 flags.cc:425] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
05:22:41.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.517444 17696 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
05:22:41.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.517659 17696 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
05:22:41.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.518224 17696 flags.cc:425] Enabled unsafe flag: --never_fsync=true
05:22:41.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.553557 17696 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
05:22:41.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.554642 17696 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.16.230.67
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.590071 17696 tablet_server_runner.cc:78] Tablet server non-default flags:
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.16.230.84:41693
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
05:22:41.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kudu_127.16.230.67.keytab
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.16.230.67
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.16.230.67:0
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data/info.pb
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.16.230.67
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.16.230.126:39619
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.16.230.67
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/logs
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 08 Jul 2026 04:09:51 UTC on ccycloud.quasar-cakjni.root.comops.site
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4492623
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
05:22:41.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.591132 17696 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
05:22:41.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.592056 17696 file_cache.cc:493] Constructed file cache file cache with capacity 419430
05:22:41.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.610754 17702 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
05:22:42.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.201330 17690 heartbeater.cc:503] Master 127.16.230.126:39619 was elected leader, sending a full tablet report...
05:22:42.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.610816 17703 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
05:22:42.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:41.612146 17696 server_base.cc:1034] running on GCE node
05:22:42.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:41.611045 17705 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
05:22:42.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.810539 17696 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
05:22:42.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.812734 17696 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
05:22:42.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.814292 17696 hybrid_clock.cc:648] HybridClock initialized: now 1783488162814261 us; error 42 us; skew 500 ppm
05:22:42.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.815272 17696 server_base.cc:834] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
05:22:42.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:42 dist-test-slave-1738 krb5kdc[17316](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488162, etypes {rep=17 tkt=17 ses=17}, kudu/127.16.230.67@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
05:22:42.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.821722 17696 init.cc:377] Logged in from keytab as kudu/127.16.230.67@KRBTEST.COM (short username kudu)
05:22:42.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.827008 17696 webserver.cc:460] Webserver started at http://127.16.230.67:37975/ using document root <none> and password file <none>
05:22:42.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.828143 17696 fs_manager.cc:359] Metadata directory not provided
05:22:42.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.828385 17696 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
05:22:42.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.828964 17696 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
05:22:42.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.833874 17696 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data/instance:
05:22:42.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "35f6a7a213e44e05a3684f885b5ca2c4"
05:22:42.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:42 on dist-test-slave-1738"
05:22:42.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.835120 17696 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal/instance:
05:22:42.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "35f6a7a213e44e05a3684f885b5ca2c4"
05:22:42.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:42 on dist-test-slave-1738"
05:22:42.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.843308 17696 fs_manager.cc:691] Time spent creating directory manager: real 0.008s	user 0.006s	sys 0.003s
05:22:42.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.849021 17713 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
05:22:42.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.850427 17696 fs_manager.cc:725] Time spent opening block manager: real 0.004s	user 0.001s	sys 0.002s
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.850751 17696 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data,/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "35f6a7a213e44e05a3684f885b5ca2c4"
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 05:22:42 on dist-test-slave-1738"
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.851076 17696 fs_report.cc:383] FS layout report
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data/data
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
05:22:42.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
05:22:42.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
05:22:42.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
05:22:42.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
05:22:42.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
05:22:42.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.891146 17696 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
05:22:42.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.897781 17696 env_posix.cc:2256] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
05:22:42.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.898260 17696 kserver.cc:163] Server-wide thread pool size limit: 3276
05:22:42.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.901315 17696 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
05:22:42.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.905872 17696 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
05:22:42.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.906114 17696 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
05:22:42.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.906338 17696 ts_tablet_manager.cc:610] Registered 0 tablets
05:22:42.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:42.906484 17696 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
05:22:43.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.053843 17696 rpc_server.cc:307] RPC server started. Bound to: 127.16.230.67:34115
05:22:43.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.053978 17825 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.16.230.67:34115 every 8 connection(s)
05:22:43.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.056895 17696 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/data/info.pb
05:22:43.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.060698 17305 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu as pid 17696
05:22:43.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.061297 17305 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal/instance
05:22:43.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:43 dist-test-slave-1738 krb5kdc[17316](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488162, etypes {rep=17 tkt=17 ses=17}, kudu/127.16.230.67@KRBTEST.COM for kudu/127.16.230.126@KRBTEST.COM
05:22:43.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.096464 17826 heartbeater.cc:347] Connected to a master server at 127.16.230.126:39619
05:22:43.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.096987 17826 heartbeater.cc:464] Registering TS with master...
05:22:43.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.097959 17826 heartbeater.cc:511] Master 127.16.230.126:39619 requested a full tablet report, sending...
05:22:43.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.100257 17363 ts_manager.cc:194] Registered new tserver with Master: 35f6a7a213e44e05a3684f885b5ca2c4 (127.16.230.67:34115)
05:22:43.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.102021 17363 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.16.230.67@KRBTEST.COM'} at 127.16.230.67:59141
05:22:43.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:43.111276 17305 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
05:22:43.118 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
05:22:43.119 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

05:22:43.134 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_kdc_env_vars {
  env_vars {
    key: "KRB5CCNAME"
    value: "/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5cc"
  }
  env_vars {
    key: "KRB5_CONFIG"
    value: "/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5.conf"
  }
  env_vars {
    key: "KRB5_KDC_PROFILE"
    value: "/tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/kdc.conf"
  }
  env_vars {
    key: "KUDU_ENABLE_KRB5_REALM_FIX"
    value: "yes"
  }
}

05:22:43.135 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

05:22:43.148 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.16.230.126"
      port: 39619
    }
    bound_http_address {
      host: "127.16.230.126"
      port: 39149
    }
  }
}

05:22:43.152 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

05:22:43.155 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.16.230.65"
      port: 41211
    }
    bound_http_address {
      host: "127.16.230.65"
      port: 39075
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.16.230.66"
      port: 44319
    }
    bound_http_address {
      host: "127.16.230.66"
      port: 33689
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.16.230.67"
      port: 34115
    }
    bound_http_address {
      host: "127.16.230.67"
      port: 37975
    }
  }
}

05:22:43.156 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
05:22:43.337 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/krb5kdc/krb5cc
05:22:43.354 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
05:22:43.497 [DEBUG - main] (Connection.java:748) [peer master-127.16.230.126:39619(127.16.230.126:39619)] connecting to peer
05:22:43.572 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.16.230.126:39619(127.16.230.126:39619)] Successfully connected to peer
05:22:43.652 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.16.230.126
05:22:43.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Jul 08 05:22:43 dist-test-slave-1738 krb5kdc[17316](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1783488155, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.16.230.126@KRBTEST.COM
05:22:43.880 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xc97a9c24, L:/127.0.0.1:59268 - R:/127.16.230.126:39619] using SASL/GSSAPI
05:22:43.920 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
05:22:43.922 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=466), Traces: [0ms] refreshing cache from master, [29ms] Sub RPC ConnectToMaster: sending RPC to server master-127.16.230.126:39619, [435ms] Sub RPC ConnectToMaster: received response from server master-127.16.230.126:39619: OK, deferred=null) after lookup
05:22:43.947 [DEBUG - main] (Connection.java:575) [peer master-127.16.230.126:39619(127.16.230.126:39619)] disconnecting while in state READY
05:22:43.955 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.16.230.126:39619(127.16.230.126:39619)] handling channelInactive
05:22:43.956 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.16.230.126:39619(127.16.230.126:39619)] cleaning up while in state READY due to: connection closed
05:22:43.956 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.16.230.126:39619(127.16.230.126:39619) for tablet Kudu Master: connection closed
05:22:43.977 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.16.230.126:39619(127.16.230.126:39619)] connecting to peer
05:22:43.982 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.16.230.126:39619(127.16.230.126:39619)] Successfully connected to peer
05:22:44.044 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x5b7626a7, L:/127.0.0.1:59280 - R:/127.16.230.126:39619] using TOKEN/null
05:22:44.048 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
05:22:44.050 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=100), Traces: [0ms] sending RPC to server master-127.16.230.126:39619, [7ms] received response from server master-127.16.230.126:39619: Network error: connection closed, [8ms] delaying RPC due to: Network error: connection closed, [27ms] refreshing cache from master, [28ms] Sub RPC ConnectToMaster: sending RPC to server master-127.16.230.126:39619, [98ms] Sub RPC ConnectToMaster: received response from server master-127.16.230.126:39619: OK, deferred=Deferred@2030887258(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
05:22:44.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.106091 17826 heartbeater.cc:503] Master 127.16.230.126:39619 was elected leader, sending a full tablet report...
05:22:44.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.125734 17363 catalog_manager.cc:1908] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:59280:
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
05:22:44.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
05:22:44.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
05:22:44.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
05:22:44.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
05:22:44.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
05:22:44.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
05:22:44.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
05:22:44.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
05:22:44.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
05:22:44.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
05:22:44.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
05:22:44.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
05:22:44.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.155028 17363 catalog_manager.cc:6854] 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.
05:22:44.209 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 6563fb5382434bab839d9497e7fc2cc6
05:22:44.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.212182 17761 tablet_service.cc:1467] Processing CreateTablet for tablet 00a421bd6757411b9d26bd2f0512348f (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=6563fb5382434bab839d9497e7fc2cc6]), partition=RANGE (key) PARTITION UNBOUNDED
05:22:44.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.215337 17761 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00a421bd6757411b9d26bd2f0512348f. 1 dirs total, 0 dirs full, 0 dirs failed
05:22:44.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.225561 17489 tablet_service.cc:1467] Processing CreateTablet for tablet 00a421bd6757411b9d26bd2f0512348f (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=6563fb5382434bab839d9497e7fc2cc6]), partition=RANGE (key) PARTITION UNBOUNDED
05:22:44.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.226217 17625 tablet_service.cc:1467] Processing CreateTablet for tablet 00a421bd6757411b9d26bd2f0512348f (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=6563fb5382434bab839d9497e7fc2cc6]), partition=RANGE (key) PARTITION UNBOUNDED
05:22:44.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.227152 17489 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00a421bd6757411b9d26bd2f0512348f. 1 dirs total, 0 dirs full, 0 dirs failed
05:22:44.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.228086 17625 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00a421bd6757411b9d26bd2f0512348f. 1 dirs total, 0 dirs full, 0 dirs failed
05:22:44.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.247227 17844 tablet_bootstrap.cc:492] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Bootstrap starting.
05:22:44.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.254467 17845 tablet_bootstrap.cc:492] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: Bootstrap starting.
05:22:44.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.256598 17844 tablet_bootstrap.cc:654] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Neither blocks nor log segments found. Creating new log.
05:22:44.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.259567 17844 log.cc:824] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Log is configured to *not* fsync() on all Append() calls
05:22:44.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.260393 17846 tablet_bootstrap.cc:492] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Bootstrap starting.
05:22:44.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.265174 17845 tablet_bootstrap.cc:654] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: Neither blocks nor log segments found. Creating new log.
05:22:44.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.267795 17846 tablet_bootstrap.cc:654] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Neither blocks nor log segments found. Creating new log.
05:22:44.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.267784 17845 log.cc:824] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: Log is configured to *not* fsync() on all Append() calls
05:22:44.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.270252 17846 log.cc:824] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Log is configured to *not* fsync() on all Append() calls
05:22:44.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.274040 17845 tablet_bootstrap.cc:492] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: No bootstrap required, opened a new log
05:22:44.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.274511 17845 ts_tablet_manager.cc:1397] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: Time spent bootstrapping tablet: real 0.021s	user 0.005s	sys 0.010s
05:22:44.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.277128 17846 tablet_bootstrap.cc:492] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: No bootstrap required, opened a new log
05:22:44.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.278584 17846 ts_tablet_manager.cc:1397] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Time spent bootstrapping tablet: real 0.019s	user 0.009s	sys 0.008s
05:22:44.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.284199 17844 tablet_bootstrap.cc:492] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: No bootstrap required, opened a new log
05:22:44.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.284972 17844 ts_tablet_manager.cc:1397] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Time spent bootstrapping tablet: real 0.038s	user 0.012s	sys 0.014s
05:22:44.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.309325 17845 raft_consensus.cc:348] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.310436 17845 raft_consensus.cc:374] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
05:22:44.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.310771 17845 raft_consensus.cc:729] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0d1b36b751b54ced93b59a74b8eaffed, State: Initialized, Role: FOLLOWER
05:22:44.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.311740 17844 raft_consensus.cc:348] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.312053 17845 consensus_queue.cc:260] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [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: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.312728 17844 raft_consensus.cc:374] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
05:22:44.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.313047 17844 raft_consensus.cc:729] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 35f6a7a213e44e05a3684f885b5ca2c4, State: Initialized, Role: FOLLOWER
05:22:44.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.313030 17846 raft_consensus.cc:348] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.314122 17846 raft_consensus.cc:374] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
05:22:44.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.314136 17844 consensus_queue.cc:260] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [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: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.314777 17846 raft_consensus.cc:729] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 1005733e2ad942729748e67d949c6fdf, State: Initialized, Role: FOLLOWER
05:22:44.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.315794 17846 consensus_queue.cc:260] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [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: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.316838 17845 ts_tablet_manager.cc:1428] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: Time spent starting tablet: real 0.042s	user 0.040s	sys 0.000s
05:22:44.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.325181 17846 ts_tablet_manager.cc:1428] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Time spent starting tablet: real 0.046s	user 0.039s	sys 0.006s
05:22:44.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.325301 17844 ts_tablet_manager.cc:1428] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Time spent starting tablet: real 0.037s	user 0.025s	sys 0.009s
05:22:44.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.383037 17850 raft_consensus.cc:482] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
05:22:44.384 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.383602 17850 raft_consensus.cc:504] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.386250 17850 leader_election.cc:288] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 35f6a7a213e44e05a3684f885b5ca2c4 (127.16.230.67:34115), 1005733e2ad942729748e67d949c6fdf (127.16.230.66:44319)
05:22:44.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.401100 17555 tablet.cc:2369] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: Can't schedule compaction. Clean time has not been advanced past its initial value.
05:22:44.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.401093 17781 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00a421bd6757411b9d26bd2f0512348f" candidate_uuid: "0d1b36b751b54ced93b59a74b8eaffed" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" is_pre_election: true
05:22:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.401929 17781 raft_consensus.cc:2454] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 0d1b36b751b54ced93b59a74b8eaffed in term 0.
05:22:44.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.403234 17444 leader_election.cc:302] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [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: 0d1b36b751b54ced93b59a74b8eaffed, 35f6a7a213e44e05a3684f885b5ca2c4; no voters: 
05:22:44.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.403505 17645 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00a421bd6757411b9d26bd2f0512348f" candidate_uuid: "0d1b36b751b54ced93b59a74b8eaffed" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1005733e2ad942729748e67d949c6fdf" is_pre_election: true
05:22:44.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.404245 17850 raft_consensus.cc:2781] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Leader pre-election won for term 1
05:22:44.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.404601 17691 tablet.cc:2369] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Can't schedule compaction. Clean time has not been advanced past its initial value.
05:22:44.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.404462 17645 raft_consensus.cc:2454] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 0d1b36b751b54ced93b59a74b8eaffed in term 0.
05:22:44.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.404639 17850 raft_consensus.cc:482] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
05:22:44.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.405064 17850 raft_consensus.cc:3037] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 0 FOLLOWER]: Advancing to term 1
05:22:44.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.410190 17850 raft_consensus.cc:504] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.411697 17850 leader_election.cc:288] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [CANDIDATE]: Term 1 election: Requested vote from peers 35f6a7a213e44e05a3684f885b5ca2c4 (127.16.230.67:34115), 1005733e2ad942729748e67d949c6fdf (127.16.230.66:44319)
05:22:44.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.412669 17781 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00a421bd6757411b9d26bd2f0512348f" candidate_uuid: "0d1b36b751b54ced93b59a74b8eaffed" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "35f6a7a213e44e05a3684f885b5ca2c4"
05:22:44.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.412848 17645 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "00a421bd6757411b9d26bd2f0512348f" candidate_uuid: "0d1b36b751b54ced93b59a74b8eaffed" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "1005733e2ad942729748e67d949c6fdf"
05:22:44.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.413271 17781 raft_consensus.cc:3037] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 0 FOLLOWER]: Advancing to term 1
05:22:44.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.413430 17645 raft_consensus.cc:3037] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 0 FOLLOWER]: Advancing to term 1
05:22:44.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.418149 17781 raft_consensus.cc:2454] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 0d1b36b751b54ced93b59a74b8eaffed in term 1.
05:22:44.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.418367 17645 raft_consensus.cc:2454] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 0d1b36b751b54ced93b59a74b8eaffed in term 1.
05:22:44.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.419294 17444 leader_election.cc:302] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [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: 0d1b36b751b54ced93b59a74b8eaffed, 35f6a7a213e44e05a3684f885b5ca2c4; no voters: 
05:22:44.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.420189 17850 raft_consensus.cc:2781] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 1 FOLLOWER]: Leader election won for term 1
05:22:44.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.422067 17850 raft_consensus.cc:686] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 1 LEADER]: Becoming Leader. State: Replica: 0d1b36b751b54ced93b59a74b8eaffed, State: Running, Role: LEADER
05:22:44.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.422953 17850 consensus_queue.cc:237] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [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: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } }
05:22:44.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:44.435537 17362 catalog_manager.cc:5495] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed reported cstate change: term changed from 0 to 1, leader changed from <none> to 0d1b36b751b54ced93b59a74b8eaffed (127.16.230.65). New cstate: current_term: 1 leader_uuid: "0d1b36b751b54ced93b59a74b8eaffed" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "0d1b36b751b54ced93b59a74b8eaffed" member_type: VOTER last_known_addr { host: "127.16.230.65" port: 41211 } health_report { overall_health: HEALTHY } } }
05:22:44.506 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 6563fb5382434bab839d9497e7fc2cc6
05:22:44.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:44.563737 17827 tablet.cc:2369] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Can't schedule compaction. Clean time has not been advanced past its initial value.
05:22:45.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.044953 17857 consensus_queue.cc:1035] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [LEADER]: Connected to new peer: Peer: permanent_uuid: "35f6a7a213e44e05a3684f885b5ca2c4" member_type: VOTER last_known_addr { host: "127.16.230.67" port: 34115 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
05:22:45.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.050220 17858 consensus_queue.cc:1035] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [LEADER]: Connected to new peer: Peer: permanent_uuid: "1005733e2ad942729748e67d949c6fdf" member_type: VOTER last_known_addr { host: "127.16.230.66" port: 44319 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
05:22:45.514 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table 6563fb5382434bab839d9497e7fc2cc6
05:22:45.542 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet 00a421bd6757411b9d26bd2f0512348f for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
05:22:45.548 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299993, tablet=00a421bd6757411b9d26bd2f0512348f@[0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)[L],1005733e2ad942729748e67d949c6fdf(127.16.230.66:44319),35f6a7a213e44e05a3684f885b5ca2c4(127.16.230.67:34115)]}]
05:22:45.550 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC ScanRequest(scannerId=null, state=OPENING, attempt=1, KuduRpc(method=Scan, tablet=null, attempt=1, TimeoutTracker(timeout=60000, elapsed=20), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.16.230.126:39619, [11ms] Sub RPC GetTableLocations: received response from server master-127.16.230.126:39619: OK, deferred=null)) after lookup
05:22:45.562 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] connecting to peer
05:22:45.567 [DEBUG - kudu-nio-0] (Connection.java:757) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] Successfully connected to peer
05:22:45.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:45.592387 17867 negotiation.cc:344] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:58686: token expired
05:22:45.597 [DEBUG - kudu-nio-0] (Negotiator.java:352) peer /127.16.230.65:41211 sent connection negotiation error: Not authorized: token expired
05:22:45.598 [DEBUG - kudu-nio-0] (Connection.java:239) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] handling channelInactive
05:22:45.598 [DEBUG - kudu-nio-0] (Connection.java:709) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
05:22:45.599 [DEBUG - kudu-nio-0] (Connection.java:748) [peer master-127.16.230.126:39619(127.16.230.126:39619)] connecting to peer
05:22:45.602 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.16.230.126:39619(127.16.230.126:39619)] Successfully connected to peer
05:22:45.607 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.16.230.126
05:22:45.653 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xaccc14a1, L:/127.0.0.1:59286 - R:/127.16.230.126:39619] using SASL/GSSAPI
05:22:45.660 [INFO - kudu-nio-1] (AsyncKuduClient.java:516) connect to master: received a new authn token
05:22:45.661 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] connecting to peer
05:22:45.663 [DEBUG - kudu-nio-0] (Connection.java:757) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] Successfully connected to peer
05:22:45.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:45.683139 17867 fault_injection.cc:43] FAULT INJECTION ENABLED!
05:22:45.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:45.683396 17867 fault_injection.cc:44] THIS SERVER MAY CRASH!
05:22:45.685 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x4660fdda, L:/127.0.0.1:58688 - R:/127.16.230.65:41211] using TOKEN/null
05:22:45.712 [DEBUG - kudu-nio-0] (AsyncKuduScanner.java:1374) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=00a421bd6757411b9d26bd2f0512348f, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
05:22:45.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.726317 17362 catalog_manager.cc:2461] Servicing SoftDeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:59286:
05:22:45.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
05:22:45.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.727044 17362 catalog_manager.cc:2645] Servicing DeleteTable request from {username='test-admin', principal='test-admin@KRBTEST.COM'} at 127.0.0.1:59286:
05:22:45.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
05:22:45.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.742403 17362 catalog_manager.cc:5782] T 00000000000000000000000000000000 P a106b55762a640c4954254d493098a97: Sending DeleteTablet for 3 replicas of tablet 00a421bd6757411b9d26bd2f0512348f
05:22:45.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.744614 17761 tablet_service.cc:1514] Processing DeleteTablet for tablet 00a421bd6757411b9d26bd2f0512348f with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 05:22:45 UTC) from {username='kudu', principal='kudu/127.16.230.126@KRBTEST.COM'} at 127.0.0.1:57186
05:22:45.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.745038 17625 tablet_service.cc:1514] Processing DeleteTablet for tablet 00a421bd6757411b9d26bd2f0512348f with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 05:22:45 UTC) from {username='kudu', principal='kudu/127.16.230.126@KRBTEST.COM'} at 127.0.0.1:45566
05:22:45.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.745971 17489 tablet_service.cc:1514] Processing DeleteTablet for tablet 00a421bd6757411b9d26bd2f0512348f with delete_type TABLET_DATA_DELETED (Table deleted at 2026-07-08 05:22:45 UTC) from {username='kudu', principal='kudu/127.16.230.126@KRBTEST.COM'} at 127.0.0.1:58680
05:22:45.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.764920 17875 tablet_replica.cc:330] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: stopping tablet replica
05:22:45.767 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.766620 17875 raft_consensus.cc:2229] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 1 FOLLOWER]: Raft consensus shutting down.
05:22:45.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.767724 17875 raft_consensus.cc:2258] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4 [term 1 FOLLOWER]: Raft consensus is shut down!
05:22:45.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.771816 17875 ts_tablet_manager.cc:1905] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Deleting tablet data with delete state TABLET_DATA_DELETED
05:22:45.776 [DEBUG - main] (Connection.java:575) [peer master-127.16.230.126:39619(127.16.230.126:39619)] disconnecting while in state READY
05:22:45.777 [DEBUG - main] (Connection.java:575) [peer master-127.16.230.126:39619(127.16.230.126:39619)] disconnecting while in state READY
05:22:45.777 [DEBUG - main] (Connection.java:575) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] disconnecting while in state READY
05:22:45.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.775611 17877 tablet_replica.cc:330] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: stopping tablet replica
05:22:45.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.776584 17877 raft_consensus.cc:2229] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 1 LEADER]: Raft consensus shutting down.
05:22:45.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.777777 17877 raft_consensus.cc:2258] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed [term 1 FOLLOWER]: Raft consensus is shut down!
05:22:45.779 [DEBUG - kudu-nio-0] (Connection.java:239) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] handling channelInactive
05:22:45.780 [DEBUG - kudu-nio-0] (Connection.java:709) [peer 0d1b36b751b54ced93b59a74b8eaffed(127.16.230.65:41211)] cleaning up while in state READY due to: connection closed
05:22:45.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.781795 17876 tablet_replica.cc:330] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: stopping tablet replica
05:22:45.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.782763 17876 raft_consensus.cc:2229] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 1 FOLLOWER]: Raft consensus shutting down.
05:22:45.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.783610 17876 raft_consensus.cc:2258] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf [term 1 FOLLOWER]: Raft consensus is shut down!
05:22:45.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.783815 17877 ts_tablet_manager.cc:1905] T 00a421bd6757411b9d26bd2f0512348f P 0d1b36b751b54ced93b59a74b8eaffed: Deleting tablet data with delete state TABLET_DATA_DELETED
05:22:45.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.787220 17876 ts_tablet_manager.cc:1905] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Deleting tablet data with delete state TABLET_DATA_DELETED
05:22:45.791 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2704) Releasing all remaining resources
05:22:45.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.799005 17875 ts_tablet_manager.cc:1918] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
05:22:45.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.799722 17875 log.cc:1196] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Deleting WAL directory at /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-2/wal/wals/00a421bd6757411b9d26bd2f0512348f
05:22:45.805 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.16.230.126:39619(127.16.230.126:39619)] handling channelInactive
05:22:45.805 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.16.230.126:39619(127.16.230.126:39619)] cleaning up while in state READY due to: connection closed
05:22:45.806 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.16.230.126:39619(127.16.230.126:39619)] handling channelInactive
05:22:45.806 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.16.230.126:39619(127.16.230.126:39619)] cleaning up while in state READY due to: connection closed
05:22:45.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.800734 17875 ts_tablet_manager.cc:1939] T 00a421bd6757411b9d26bd2f0512348f P 35f6a7a213e44e05a3684f885b5ca2c4: Deleting consensus metadata
05:22:45.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.804208 17349 catalog_manager.cc:4841] TS 35f6a7a213e44e05a3684f885b5ca2c4 (127.16.230.67:34115): tablet 00a421bd6757411b9d26bd2f0512348f (table TestAuthnTokenReacquireOpen-table [id=6563fb5382434bab839d9497e7fc2cc6]) successfully deleted
05:22:45.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.807888 17305 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu with pid 17424
05:22:45.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.807981 17876 ts_tablet_manager.cc:1918] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
05:22:45.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.809039 17876 log.cc:1196] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Deleting WAL directory at /tmp/dist-test-taskzXBHjk/test-tmp/mini-kudu-cluster2749874828202613726/ts-1/wal/wals/00a421bd6757411b9d26bd2f0512348f
05:22:45.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.809821 17876 ts_tablet_manager.cc:1939] T 00a421bd6757411b9d26bd2f0512348f P 1005733e2ad942729748e67d949c6fdf: Deleting consensus metadata
05:22:45.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.814443 17350 catalog_manager.cc:4841] TS 1005733e2ad942729748e67d949c6fdf (127.16.230.66:44319): tablet 00a421bd6757411b9d26bd2f0512348f (table TestAuthnTokenReacquireOpen-table [id=6563fb5382434bab839d9497e7fc2cc6]) successfully deleted
05:22:45.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:45.838732 17347 proxy.cc:239] Call had error, refreshing address and retrying: Network error: recv got EOF from 127.16.230.65:41211 (error 108)
05:22:45.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.838949 17305 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu with pid 17560
05:22:45.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 05:22:45.846678 17347 catalog_manager.cc:4584] TS 0d1b36b751b54ced93b59a74b8eaffed (127.16.230.65:41211): DeleteTablet:TABLET_DATA_DELETED RPC failed for tablet 00a421bd6757411b9d26bd2f0512348f: Network error: Client connection negotiation failed: client connection to 127.16.230.65:41211: connect: Connection refused (error 111)
05:22:45.867 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.866549 17305 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu with pid 17696
05:22:45.893 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 05:22:45.891390 17305 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskzXBHjk/build/tsan/bin/kudu with pid 17329
05:22:47.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T05:22:47Z chronyd exiting

Time: 15.013

OK (1 test)

