JUnit version 4.13.2
.03:07:02.851 [INFO - main] (RandomUtils.java:49) Using random seed: 1786504022846
03:07:02.867 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
03:07:02.914 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
03:07:02.922 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskJGZZI1/test-tmp
03:07:02.924 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/debug/bin
03:07:02.928 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskJGZZI1/java/build/dist-test/../../../build/debug/bin/kudu, test, mini_cluster, --serialization=pb]
03:07:03.160 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 1
  num_tservers: 3
  enable_kerberos: true
  cluster_root: "/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761"
  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 {
  }
}

03:07:03.174 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
03:07:03.175 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

03:07:03.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:03.177122  5593 mini_oidc.cc:152] Starting JWKS server
03:07:03.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:03.179569  5593 webserver.cc:460] Webserver started at http://127.0.0.1:34525/ using document root <none> and password file <none>
03:07:03.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:03.179770  5593 mini_oidc.cc:189] Starting OIDC Discovery server
03:07:03.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:03.180106  5593 webserver.cc:460] Webserver started at http://127.0.0.1:46375/ using document root <none> and password file <none>
03:07:03.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Loading random data
03:07:03.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Initializing database '/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/principal' for realm 'KRBTEST.COM',
03:07:03.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master key name 'K/M@KRBTEST.COM'
03:07:03.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:03 dist-test-slave-nj21 krb5kdc[5619](info): setting up network...
03:07:03.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: setsockopt(10,IPV6_V6ONLY,1) worked
03:07:03.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:03 dist-test-slave-nj21 krb5kdc[5619](info): set up 2 sockets
03:07:03.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:03 dist-test-slave-nj21 krb5kdc[5619](info): commencing operation
03:07:03.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) krb5kdc: starting...
03:07:04.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.409317  5593 mini_kdc.cc:121] Time spent starting KDC: real 1.222s	user 0.000s	sys 0.006s
03:07:04.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-admin@KRBTEST.COM; defaulting to no policy
03:07:04.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
03:07:04.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-admin@KRBTEST.COM" created.
03:07:04.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for test-user@KRBTEST.COM; defaulting to no policy
03:07:04.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
03:07:04.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "test-user@KRBTEST.COM" created.
03:07:04.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for joe-interloper@KRBTEST.COM; defaulting to no policy
03:07:04.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
03:07:04.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "joe-interloper@KRBTEST.COM" created.
03:07:04.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal slave/admin@KRBTEST.COM with password.
03:07:04.482 [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-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/test-user.keytab.
03:07:04.482 [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-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/test-user.keytab.
03:07:04.492 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:04 dist-test-slave-nj21 krb5kdc[5619](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504024, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
03:07:04.492 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Password for test-admin@KRBTEST.COM: 
03:07:04.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T03:07:04Z chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH -IPV6 +DEBUG)
03:07:04.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T03:07:04Z Disabled control of system clock
03:07:04.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.5.118.126@KRBTEST.COM; defaulting to no policy
03:07:04.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:04.513 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.5.118.126@KRBTEST.COM" created.
03:07:04.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:04.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.126 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.126.keytab.
03:07:04.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.126 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.126.keytab.
03:07:04.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.527463  5593 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:04.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:04.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/wal
03:07:04.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data
03:07:04.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
03:07:04.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
03:07:04.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
03:07:04.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:04.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/logs
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data/info.pb
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
03:07:04.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.126:36119
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.126
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.126.keytab
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.126
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:04.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.118.126:36119
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:34525/jwks.json
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
03:07:04.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000 with env {KRB5CCNAME=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
03:07:04.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.639921  5642 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
03:07:04.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.640156  5642 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
03:07:04.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.640198  5642 flags.cc:425] Enabled unsafe flag: --never_fsync=true
03:07:04.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.644017  5642 flags.cc:425] Enabled experimental flag: --authn_token_validity_seconds=1
03:07:04.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.644064  5642 flags.cc:425] Enabled experimental flag: --ipki_ca_key_size=768
03:07:04.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.644084  5642 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
03:07:04.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.644098  5642 flags.cc:425] Enabled experimental flag: --tsk_num_rsa_bits=512
03:07:04.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.644112  5642 flags.cc:425] Enabled experimental flag: --rpc_reuseport=true
03:07:04.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.644125  5642 flags.cc:425] Enabled experimental flag: --enable_jwt_token_auth=true
03:07:04.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.644137  5642 flags.cc:425] Enabled experimental flag: --jwks_url=http://localhost:34525/jwks.json
03:07:04.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.649657  5642 master_runner.cc:386] Master server non-default flags:
03:07:04.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:04.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:04.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:04.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:04.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data
03:07:04.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/wal
03:07:04.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --authn_token_validity_seconds=1
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.118.126:36119
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.126.keytab
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.126
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.126:36119
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
03:07:04.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
03:07:04.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
03:07:04.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:34525/jwks.json
03:07:04.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:04.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
03:07:04.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
03:07:04.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
03:07:04.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:04.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:04.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data/info.pb
03:07:04.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.126
03:07:04.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:04.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
03:07:04.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.5642
03:07:04.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:04.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
03:07:04.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
03:07:04.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:04.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/logs
03:07:04.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:04.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
03:07:04.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
03:07:04.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
03:07:04.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
03:07:04.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
03:07:04.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
03:07:04.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 02:22:35 UTC on ccycloud.quasar-gitnpj.root.comops.site
03:07:04.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581827
03:07:04.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.651628  5642 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
03:07:04.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.652962  5642 file_cache.cc:493] Constructed file cache file cache with capacity 419430
03:07:04.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.665562  5655 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
03:07:04.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.665721  5651 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
03:07:04.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.665769  5642 server_base.cc:1034] running on GCE node
03:07:04.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.665562  5650 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
03:07:04.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.667052  5642 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
03:07:04.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.669956  5642 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
03:07:04.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.671485  5642 hybrid_clock.cc:648] HybridClock initialized: now 1786504024671383 us; error 112 us; skew 500 ppm
03:07:04.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:04 dist-test-slave-nj21 krb5kdc[5619](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504024, etypes {rep=17 tkt=17 ses=17}, kudu/127.5.118.126@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
03:07:04.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.674808  5642 init.cc:377] Logged in from keytab as kudu/127.5.118.126@KRBTEST.COM (short username kudu)
03:07:04.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.675776  5642 webserver.cc:460] Webserver started at http://127.5.118.126:35349/ using document root <none> and password file <none>
03:07:04.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.676322  5642 fs_manager.cc:359] Metadata directory not provided
03:07:04.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.676388  5642 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
03:07:04.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.676633  5642 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
03:07:04.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.678750  5642 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data/instance:
03:07:04.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "18ee3d984fbb4450ab246e808e123c55"
03:07:04.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:04 on dist-test-slave-nj21"
03:07:04.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.679391  5642 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/wal/instance:
03:07:04.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "18ee3d984fbb4450ab246e808e123c55"
03:07:04.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:04 on dist-test-slave-nj21"
03:07:04.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.684957  5642 fs_manager.cc:691] Time spent creating directory manager: real 0.005s	user 0.001s	sys 0.003s
03:07:04.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.688182  5664 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
03:07:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.689610  5642 fs_manager.cc:725] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
03:07:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.689715  5642 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data,/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/wal
03:07:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "18ee3d984fbb4450ab246e808e123c55"
03:07:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:04 on dist-test-slave-nj21"
03:07:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.689795  5642 fs_report.cc:383] FS layout report
03:07:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
03:07:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/wal
03:07:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/wal
03:07:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data/data
03:07:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
03:07:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
03:07:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
03:07:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
03:07:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
03:07:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
03:07:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
03:07:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
03:07:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
03:07:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
03:07:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
03:07:04.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.713969  5642 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
03:07:04.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.717193  5642 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
03:07:04.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.717415  5642 kserver.cc:163] Server-wide thread pool size limit: 3276
03:07:04.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.724475  5642 rpc_server.cc:307] RPC server started. Bound to: 127.5.118.126:36119
03:07:04.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.724721  5765 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.5.118.126:36119 every 8 connection(s)
03:07:04.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.725526  5642 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/data/info.pb
03:07:04.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.726818  5593 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu as pid 5642
03:07:04.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.726948  5593 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/master-0/wal/instance
03:07:04.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.728606  5767 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
03:07:04.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.734406  5767 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55: Bootstrap starting.
03:07:04.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.736825  5767 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55: Neither blocks nor log segments found. Creating new log.
03:07:04.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.737583  5767 log.cc:824] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55: Log is configured to *not* fsync() on all Append() calls
03:07:04.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.739647  5767 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55: No bootstrap required, opened a new log
03:07:04.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:04 dist-test-slave-nj21 krb5kdc[5619](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504024, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.5.118.126@KRBTEST.COM
03:07:04.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.744110  5767 raft_consensus.cc:348] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "18ee3d984fbb4450ab246e808e123c55" member_type: VOTER last_known_addr { host: "127.5.118.126" port: 36119 } }
03:07:04.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.744354  5767 raft_consensus.cc:374] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
03:07:04.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.744407  5767 raft_consensus.cc:729] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 18ee3d984fbb4450ab246e808e123c55, State: Initialized, Role: FOLLOWER
03:07:04.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.744879  5767 consensus_queue.cc:260] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [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: "18ee3d984fbb4450ab246e808e123c55" member_type: VOTER last_known_addr { host: "127.5.118.126" port: 36119 } }
03:07:04.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.745047  5767 raft_consensus.cc:388] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 0 FOLLOWER]: Only one voter in the Raft config. Triggering election immediately
03:07:04.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.745105  5767 raft_consensus.cc:482] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 0 FOLLOWER]: Starting leader election (initial election of a single-replica configuration)
03:07:04.745 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.745195  5767 raft_consensus.cc:3037] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 0 FOLLOWER]: Advancing to term 1
03:07:04.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.746162  5767 raft_consensus.cc:504] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "18ee3d984fbb4450ab246e808e123c55" member_type: VOTER last_known_addr { host: "127.5.118.126" port: 36119 } }
03:07:04.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.746556  5767 leader_election.cc:302] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [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: 18ee3d984fbb4450ab246e808e123c55; no voters: 
03:07:04.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.746974  5767 leader_election.cc:288] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [CANDIDATE]: Term 1 election: Requested vote from peers 
03:07:04.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.747280  5773 raft_consensus.cc:2781] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 1 FOLLOWER]: Leader election won for term 1
03:07:04.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.747470  5773 raft_consensus.cc:686] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [term 1 LEADER]: Becoming Leader. State: Replica: 18ee3d984fbb4450ab246e808e123c55, State: Running, Role: LEADER
03:07:04.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.747797  5773 consensus_queue.cc:237] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [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: "18ee3d984fbb4450ab246e808e123c55" member_type: VOTER last_known_addr { host: "127.5.118.126" port: 36119 } }
03:07:04.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.748363  5767 sys_catalog.cc:564] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [sys.catalog]: configured and running, proceeding with master startup.
03:07:04.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.749075  5773 sys_catalog.cc:455] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 1 leader_uuid: "18ee3d984fbb4450ab246e808e123c55" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "18ee3d984fbb4450ab246e808e123c55" member_type: VOTER last_known_addr { host: "127.5.118.126" port: 36119 } } }
03:07:04.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.749323  5774 sys_catalog.cc:455] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 18ee3d984fbb4450ab246e808e123c55. Latest consensus state: current_term: 1 leader_uuid: "18ee3d984fbb4450ab246e808e123c55" committed_config { opid_index: -1 OBSOLETE_local: true peers { permanent_uuid: "18ee3d984fbb4450ab246e808e123c55" member_type: VOTER last_known_addr { host: "127.5.118.126" port: 36119 } } }
03:07:04.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.749850  5773 sys_catalog.cc:458] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [sys.catalog]: This master's current role is: LEADER
03:07:04.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.749847  5774 sys_catalog.cc:458] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55 [sys.catalog]: This master's current role is: LEADER
03:07:04.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.750509  5790 catalog_manager.cc:1475] Loading table and tablet metadata into memory...
03:07:04.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.753453  5790 catalog_manager.cc:1484] Initializing Kudu cluster ID...
03:07:04.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.758700  5790 catalog_manager.cc:1347] Generated new cluster ID: ab5789f170f0484183439eb98a0326dc
03:07:04.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.758757  5790 catalog_manager.cc:1495] Initializing Kudu internal certificate authority...
03:07:04.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.772516  5790 catalog_manager.cc:1370] Generated new certificate authority record
03:07:04.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.773494  5790 catalog_manager.cc:1504] Loading token signing keys...
03:07:04.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.784608  5790 catalog_manager.cc:5868] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55: Generated new TSK 0
03:07:04.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.785373  5790 catalog_manager.cc:1514] Initializing in-progress tserver states...
03:07:04.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.5.118.65@KRBTEST.COM; defaulting to no policy
03:07:04.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:04.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.5.118.65@KRBTEST.COM" created.
03:07:04.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:04.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.65 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.65.keytab.
03:07:04.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.65 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.65.keytab.
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.820802  5593 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/logs
03:07:04.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data/info.pb
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:04.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.65:0
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.118.65
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.65
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.118.126:36119
03:07:04.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.65.keytab
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.65
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
03:07:04.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
03:07:04.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.935319  5815 flags.cc:425] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
03:07:04.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.935571  5815 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
03:07:04.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.935590  5815 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
03:07:04.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.935626  5815 flags.cc:425] Enabled unsafe flag: --never_fsync=true
03:07:04.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.939587  5815 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
03:07:04.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.939711  5815 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.5.118.65
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.944998  5815 tablet_server_runner.cc:78] Tablet server non-default flags:
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.65.keytab
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.65
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.65:0
03:07:04.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data/info.pb
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.65
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.118.126:36119
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.5815
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.118.65
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/logs
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
03:07:04.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 02:22:35 UTC on ccycloud.quasar-gitnpj.root.comops.site
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581827
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.945945  5815 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
03:07:04.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.946874  5815 file_cache.cc:493] Constructed file cache file cache with capacity 419430
03:07:04.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.956375  5829 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
03:07:04.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.956382  5828 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
03:07:04.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.956432  5815 server_base.cc:1034] running on GCE node
03:07:04.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:04.956379  5833 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
03:07:04.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.957393  5815 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
03:07:04.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.958091  5815 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
03:07:04.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.959278  5815 hybrid_clock.cc:648] HybridClock initialized: now 1786504024959208 us; error 85 us; skew 500 ppm
03:07:04.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:04 dist-test-slave-nj21 krb5kdc[5619](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504024, etypes {rep=17 tkt=17 ses=17}, kudu/127.5.118.65@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
03:07:04.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.962107  5815 init.cc:377] Logged in from keytab as kudu/127.5.118.65@KRBTEST.COM (short username kudu)
03:07:04.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.963034  5815 webserver.cc:460] Webserver started at http://127.5.118.65:43685/ using document root <none> and password file <none>
03:07:04.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.963611  5815 fs_manager.cc:359] Metadata directory not provided
03:07:04.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.963703  5815 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
03:07:04.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.963974  5815 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
03:07:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.965896  5815 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data/instance:
03:07:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5c81f9c5db73425586f2d6b4fb54fca1"
03:07:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:04 on dist-test-slave-nj21"
03:07:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.966492  5815 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal/instance:
03:07:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5c81f9c5db73425586f2d6b4fb54fca1"
03:07:04.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:04 on dist-test-slave-nj21"
03:07:04.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.970415  5815 fs_manager.cc:691] Time spent creating directory manager: real 0.004s	user 0.006s	sys 0.000s
03:07:04.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.972793  5843 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.973999  5815 fs_manager.cc:725] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.974107  5815 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data,/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5c81f9c5db73425586f2d6b4fb54fca1"
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:04 on dist-test-slave-nj21"
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.974208  5815 fs_report.cc:383] FS layout report
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data/data
03:07:04.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
03:07:04.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
03:07:04.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.992812  5815 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
03:07:04.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.995868  5815 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
03:07:04.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.996049  5815 kserver.cc:163] Server-wide thread pool size limit: 3276
03:07:04.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.996695  5815 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
03:07:04.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.997570  5815 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
03:07:04.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.997619  5815 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
03:07:04.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.997673  5815 ts_tablet_manager.cc:610] Registered 0 tablets
03:07:04.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:04.997694  5815 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
03:07:05.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.006385  5815 rpc_server.cc:307] RPC server started. Bound to: 127.5.118.65:42281
03:07:05.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.006419  6048 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.5.118.65:42281 every 8 connection(s)
03:07:05.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.007329  5815 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/data/info.pb
03:07:05.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.009486  5593 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu as pid 5815
03:07:05.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.009588  5593 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal/instance
03:07:05.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:05 dist-test-slave-nj21 krb5kdc[5619](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504024, etypes {rep=17 tkt=17 ses=17}, kudu/127.5.118.65@KRBTEST.COM for kudu/127.5.118.126@KRBTEST.COM
03:07:05.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.5.118.66@KRBTEST.COM; defaulting to no policy
03:07:05.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:05.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.5.118.66@KRBTEST.COM" created.
03:07:05.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.024785  6049 heartbeater.cc:347] Connected to a master server at 127.5.118.126:36119
03:07:05.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.025018  6049 heartbeater.cc:464] Registering TS with master...
03:07:05.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.025489  6049 heartbeater.cc:511] Master 127.5.118.126:36119 requested a full tablet report, sending...
03:07:05.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.027063  5702 ts_manager.cc:194] Registered new tserver with Master: 5c81f9c5db73425586f2d6b4fb54fca1 (127.5.118.65:42281)
03:07:05.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.028672  5702 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.5.118.65@KRBTEST.COM'} at 127.5.118.65:47477
03:07:05.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:05.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.66 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.66.keytab.
03:07:05.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.66 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.66.keytab.
03:07:05.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.040655  5593 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:05.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/logs
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data/info.pb
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
03:07:05.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.66:0
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.118.66
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.66
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.118.126:36119
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.66.keytab
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.66
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:05.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
03:07:05.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
03:07:05.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.153059  6059 flags.cc:425] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
03:07:05.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.153321  6059 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
03:07:05.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.153353  6059 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
03:07:05.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.153404  6059 flags.cc:425] Enabled unsafe flag: --never_fsync=true
03:07:05.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.157579  6059 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
03:07:05.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.157693  6059 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.5.118.66
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.162895  6059 tablet_server_runner.cc:78] Tablet server non-default flags:
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.66.keytab
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.66
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.66:0
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data/info.pb
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.66
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.118.126:36119
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.6059
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.118.66
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/logs
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 02:22:35 UTC on ccycloud.quasar-gitnpj.root.comops.site
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581827
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.164273  6059 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
03:07:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.165657  6059 file_cache.cc:493] Constructed file cache file cache with capacity 419430
03:07:05.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.175374  6078 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
03:07:05.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.175367  6070 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
03:07:05.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.175438  6059 server_base.cc:1034] running on GCE node
03:07:05.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.175369  6076 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
03:07:05.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.176055  6059 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
03:07:05.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.176826  6059 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
03:07:05.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.178030  6059 hybrid_clock.cc:648] HybridClock initialized: now 1786504025177999 us; error 46 us; skew 500 ppm
03:07:05.181 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:05 dist-test-slave-nj21 krb5kdc[5619](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504025, etypes {rep=17 tkt=17 ses=17}, kudu/127.5.118.66@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
03:07:05.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.181341  6059 init.cc:377] Logged in from keytab as kudu/127.5.118.66@KRBTEST.COM (short username kudu)
03:07:05.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.182343  6059 webserver.cc:460] Webserver started at http://127.5.118.66:45435/ using document root <none> and password file <none>
03:07:05.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.183045  6059 fs_manager.cc:359] Metadata directory not provided
03:07:05.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.183133  6059 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
03:07:05.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.183357  6059 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
03:07:05.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.185875  6059 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data/instance:
03:07:05.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8276a3e7325b45e09a4716e0c1f47952"
03:07:05.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:05 on dist-test-slave-nj21"
03:07:05.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.186393  6059 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal/instance:
03:07:05.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8276a3e7325b45e09a4716e0c1f47952"
03:07:05.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:05 on dist-test-slave-nj21"
03:07:05.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.190197  6059 fs_manager.cc:691] Time spent creating directory manager: real 0.004s	user 0.000s	sys 0.005s
03:07:05.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.192554  6090 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
03:07:05.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.193812  6059 fs_manager.cc:725] Time spent opening block manager: real 0.002s	user 0.003s	sys 0.000s
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.193907  6059 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data,/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8276a3e7325b45e09a4716e0c1f47952"
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:05 on dist-test-slave-nj21"
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.193979  6059 fs_report.cc:383] FS layout report
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data/data
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
03:07:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
03:07:05.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.215835  6059 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
03:07:05.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.218978  6059 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
03:07:05.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.219133  6059 kserver.cc:163] Server-wide thread pool size limit: 3276
03:07:05.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.219764  6059 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
03:07:05.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.220597  6059 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
03:07:05.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.220896  6059 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
03:07:05.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.220968  6059 ts_tablet_manager.cc:610] Registered 0 tablets
03:07:05.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.220983  6059 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
03:07:05.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.229838  6059 rpc_server.cc:307] RPC server started. Bound to: 127.5.118.66:41643
03:07:05.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.229883  6286 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.5.118.66:41643 every 8 connection(s)
03:07:05.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.230690  6059 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/data/info.pb
03:07:05.237 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.237773  5593 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu as pid 6059
03:07:05.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.237870  5593 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal/instance
03:07:05.240 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:05 dist-test-slave-nj21 krb5kdc[5619](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504025, etypes {rep=17 tkt=17 ses=17}, kudu/127.5.118.66@KRBTEST.COM for kudu/127.5.118.126@KRBTEST.COM
03:07:05.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.246140  6287 heartbeater.cc:347] Connected to a master server at 127.5.118.126:36119
03:07:05.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.246433  6287 heartbeater.cc:464] Registering TS with master...
03:07:05.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.247049  6287 heartbeater.cc:511] Master 127.5.118.126:36119 requested a full tablet report, sending...
03:07:05.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) WARNING: no policy specified for kudu/127.5.118.67@KRBTEST.COM; defaulting to no policy
03:07:05.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.248325  5702 ts_manager.cc:194] Registered new tserver with Master: 8276a3e7325b45e09a4716e0c1f47952 (127.5.118.66:41643)
03:07:05.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.248970  5702 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.5.118.66@KRBTEST.COM'} at 127.5.118.66:47501
03:07:05.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:05.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Principal "kudu/127.5.118.67@KRBTEST.COM" created.
03:07:05.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Authenticating as principal test-admin/admin@KRBTEST.COM with password.
03:07:05.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.67 with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.67.keytab.
03:07:05.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Entry for principal kudu/127.5.118.67 with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.67.keytab.
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.266330  5593 external_mini_cluster.cc:1326] Running /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:05.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/logs
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data/info.pb
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.67:0
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.118.67
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.67
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.118.126:36119
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.67.keytab
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.67
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=test-admin
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=test-user
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:05.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:05.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:05.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
03:07:05.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5 with env {KRB5CCNAME=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5cc,KRB5_CONFIG=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5.conf,KRB5_KDC_PROFILE=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kdc.conf,KUDU_ENABLE_KRB5_REALM_FIX=yes}
03:07:05.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.380762  6297 flags.cc:425] Enabled unsafe flag: --rpc_inject_invalid_authn_token_ratio=0.5
03:07:05.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.381003  6297 flags.cc:425] Enabled unsafe flag: --openssl_security_level_override=0
03:07:05.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.381021  6297 flags.cc:425] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
03:07:05.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.381057  6297 flags.cc:425] Enabled unsafe flag: --never_fsync=true
03:07:05.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.385023  6297 flags.cc:425] Enabled experimental flag: --ipki_server_key_size=768
03:07:05.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.385113  6297 flags.cc:425] Enabled experimental flag: --local_ip_for_outbound_sockets=127.5.118.67
03:07:05.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.390180  6297 tablet_server_runner.cc:78] Tablet server non-default flags:
03:07:05.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
03:07:05.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.118.84:38719
03:07:05.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
03:07:05.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_inject_invalid_authn_token_ratio=0.5
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --keytab_file=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/kudu_127.5.118.67.keytab
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --principal=kudu/127.5.118.67
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.118.67:0
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_authentication=required
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_default_keepalive_time_ms=120000
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --superuser_acl=<redacted>
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --user_acl=<redacted>
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data/info.pb
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.118.67
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.118.126:36119
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.6297
03:07:05.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.118.67
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/logs
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.18.0-SNAPSHOT
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision c2d25d5f57d102d9ad9869eaf208d035a22e18aa
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by systest at 12 Aug 2026 02:22:35 UTC on ccycloud.quasar-gitnpj.root.comops.site
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 4581827
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.391165  6297 env_posix.cc:2256] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
03:07:05.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.392159  6297 file_cache.cc:493] Constructed file cache file cache with capacity 419430
03:07:05.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.401774  6307 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
03:07:05.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.401782  6304 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
03:07:05.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.401973  6297 server_base.cc:1034] running on GCE node
03:07:05.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:05.401989  6305 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
03:07:05.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.402899  6297 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
03:07:05.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.403518  6297 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
03:07:05.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.404695  6297 hybrid_clock.cc:648] HybridClock initialized: now 1786504025404676 us; error 60 us; skew 500 ppm
03:07:05.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:05 dist-test-slave-nj21 krb5kdc[5619](info): AS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504025, etypes {rep=17 tkt=17 ses=17}, kudu/127.5.118.67@KRBTEST.COM for krbtgt/KRBTEST.COM@KRBTEST.COM
03:07:05.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.407361  6297 init.cc:377] Logged in from keytab as kudu/127.5.118.67@KRBTEST.COM (short username kudu)
03:07:05.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.408253  6297 webserver.cc:460] Webserver started at http://127.5.118.67:40519/ using document root <none> and password file <none>
03:07:05.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.408771  6297 fs_manager.cc:359] Metadata directory not provided
03:07:05.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.408830  6297 fs_manager.cc:365] Using write-ahead log directory (fs_wal_dir) as metadata directory
03:07:05.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.408991  6297 server_base.cc:882] This appears to be a new deployment of Kudu; creating new FS layout
03:07:05.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.410830  6297 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data/instance:
03:07:05.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b17565600ba24fcc9e51f441cc5c1a7a"
03:07:05.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:05 on dist-test-slave-nj21"
03:07:05.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.411331  6297 fs_manager.cc:1063] Generated new instance metadata in path /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal/instance:
03:07:05.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b17565600ba24fcc9e51f441cc5c1a7a"
03:07:05.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:05 on dist-test-slave-nj21"
03:07:05.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.415012  6297 fs_manager.cc:691] Time spent creating directory manager: real 0.003s	user 0.006s	sys 0.000s
03:07:05.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.417346  6321 log_block_manager.cc:3774] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
03:07:05.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.418530  6297 fs_manager.cc:725] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
03:07:05.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.418628  6297 fs_manager.cc:642] Opened local filesystem: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data,/tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal
03:07:05.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b17565600ba24fcc9e51f441cc5c1a7a"
03:07:05.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-08-12 03:07:05 on dist-test-slave-nj21"
03:07:05.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.418697  6297 fs_report.cc:383] FS layout report
03:07:05.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data/data
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
03:07:05.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
03:07:05.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.448091  6297 rpc_server.cc:225] running with OpenSSL 1.0.2k-fips  26 Jan 2017
03:07:05.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.451074  6297 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
03:07:05.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.451225  6297 kserver.cc:163] Server-wide thread pool size limit: 3276
03:07:05.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.451784  6297 txn_system_client.cc:433] TxnSystemClient initialization is disabled...
03:07:05.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.452582  6297 ts_tablet_manager.cc:579] Loaded tablet metadata (0 total tablets, 0 live tablets)
03:07:05.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.452628  6297 ts_tablet_manager.cc:525] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
03:07:05.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.452668  6297 ts_tablet_manager.cc:610] Registered 0 tablets
03:07:05.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.452682  6297 ts_tablet_manager.cc:589] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
03:07:05.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.461163  6297 rpc_server.cc:307] RPC server started. Bound to: 127.5.118.67:42499
03:07:05.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.461189  6512 acceptor_pool.cc:254] collecting diagnostics on the listening RPC socket 127.5.118.67:42499 every 8 connection(s)
03:07:05.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.462082  6297 server_base.cc:1166] Dumped server information to /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/data/info.pb
03:07:05.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.463450  5593 external_mini_cluster.cc:1388] Started /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu as pid 6297
03:07:05.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.463536  5593 external_mini_cluster.cc:1402] Reading /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal/instance
03:07:05.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:05 dist-test-slave-nj21 krb5kdc[5619](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504025, etypes {rep=17 tkt=17 ses=17}, kudu/127.5.118.67@KRBTEST.COM for kudu/127.5.118.126@KRBTEST.COM
03:07:05.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.477053  6514 heartbeater.cc:347] Connected to a master server at 127.5.118.126:36119
03:07:05.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.477304  6514 heartbeater.cc:464] Registering TS with master...
03:07:05.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.477833  6514 heartbeater.cc:511] Master 127.5.118.126:36119 requested a full tablet report, sending...
03:07:05.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.478842  5702 ts_manager.cc:194] Registered new tserver with Master: b17565600ba24fcc9e51f441cc5c1a7a (127.5.118.67:42499)
03:07:05.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.479463  5702 master_service.cc:496] Signed X509 certificate for tserver {username='kudu', principal='kudu/127.5.118.67@KRBTEST.COM'} at 127.5.118.67:46987
03:07:05.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:05.489470  5593 external_mini_cluster.cc:930] 3 TS(s) registered with all masters
03:07:05.490 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
03:07:05.492 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_kdc_env_vars {
}

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

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

03:07:05.511 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.5.118.126"
      port: 36119
    }
    bound_http_address {
      host: "127.5.118.126"
      port: 35349
    }
  }
}

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

03:07:05.517 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.5.118.65"
      port: 42281
    }
    bound_http_address {
      host: "127.5.118.65"
      port: 43685
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.5.118.66"
      port: 41643
    }
    bound_http_address {
      host: "127.5.118.66"
      port: 45435
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.5.118.67"
      port: 42499
    }
    bound_http_address {
      host: "127.5.118.67"
      port: 40519
    }
  }
}

03:07:05.517 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
03:07:05.680 [DEBUG - main] (SecurityUtil.java:105) Using ticketCache: /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/krb5kdc/krb5cc
03:07:05.699 [DEBUG - main] (SecurityUtil.java:119) Logged in as subject: test-admin@KRBTEST.COM
03:07:05.865 [DEBUG - main] (Connection.java:748) [peer master-127.5.118.126:36119(127.5.118.126:36119)] connecting to peer
03:07:05.945 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.5.118.126:36119(127.5.118.126:36119)] Successfully connected to peer
03:07:06.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.031584  6049 heartbeater.cc:503] Master 127.5.118.126:36119 was elected leader, sending a full tablet report...
03:07:06.032 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.5.118.126
03:07:06.207 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Aug 12 03:07:06 dist-test-slave-nj21 krb5kdc[5619](info): TGS_REQ (2 etypes {17 16}) 127.0.0.1: ISSUE: authtime 1786504024, etypes {rep=17 tkt=17 ses=17}, test-admin@KRBTEST.COM for kudu/127.5.118.126@KRBTEST.COM
03:07:06.239 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xd94ef2a0, L:/127.0.0.1:46750 - R:/127.5.118.126:36119] using SASL/GSSAPI
03:07:06.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.251497  6287 heartbeater.cc:503] Master 127.5.118.126:36119 was elected leader, sending a full tablet report...
03:07:06.275 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
03:07:06.276 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTabletServers, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=463), Traces: [0ms] refreshing cache from master, [36ms] Sub RPC ConnectToMaster: sending RPC to server master-127.5.118.126:36119, [434ms] Sub RPC ConnectToMaster: received response from server master-127.5.118.126:36119: OK, deferred=null) after lookup
03:07:06.296 [DEBUG - main] (Connection.java:575) [peer master-127.5.118.126:36119(127.5.118.126:36119)] disconnecting while in state READY
03:07:06.301 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.5.118.126:36119(127.5.118.126:36119)] handling channelInactive
03:07:06.302 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.5.118.126:36119(127.5.118.126:36119)] cleaning up while in state READY due to: connection closed
03:07:06.303 [INFO - kudu-nio-0] (AsyncKuduClient.java:2355) Invalidating location master-127.5.118.126:36119(127.5.118.126:36119) for tablet Kudu Master: connection closed
03:07:06.313 [DEBUG - pool-2-thread-1] (Connection.java:748) [peer master-127.5.118.126:36119(127.5.118.126:36119)] connecting to peer
03:07:06.316 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.5.118.126:36119(127.5.118.126:36119)] Successfully connected to peer
03:07:06.361 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x0c7c785e, L:/127.0.0.1:46758 - R:/127.5.118.126:36119] using TOKEN/null
03:07:06.364 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
03:07:06.365 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=Kudu Master, attempt=2, TimeoutTracker(timeout=50000, elapsed=68), Traces: [0ms] sending RPC to server master-127.5.118.126:36119, [5ms] received response from server master-127.5.118.126:36119: Network error: connection closed, [6ms] delaying RPC due to: Network error: connection closed, [16ms] refreshing cache from master, [16ms] Sub RPC ConnectToMaster: sending RPC to server master-127.5.118.126:36119, [66ms] Sub RPC ConnectToMaster: received response from server master-127.5.118.126:36119: OK, deferred=Deferred@209516387(state=PENDING, result=null, callback=wakeup thread main, errback=wakeup thread main)) after lookup
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.433367  5702 catalog_manager.cc:1908] Servicing CreateTable request from {username='test-admin'} at 127.0.0.1:46758:
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "TestAuthnTokenReacquireOpen-table"
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
03:07:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column1_i"
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column2_i"
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: INT32
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column3_s"
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
03:07:06.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: true
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     encoding: DICT_ENCODING
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     compression: LZ4
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 4096
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "column4_b"
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: BOOL
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: false
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
03:07:06.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
03:07:06.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
03:07:06.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:06.437861  5702 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.
03:07:06.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.461210  6409 tablet_service.cc:1467] Processing CreateTablet for tablet da5f80c40aa44e8ca87671c3016767e5 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=e72fd1cc52074fb6b0c83c481e46409d]), partition=RANGE (key) PARTITION UNBOUNDED
03:07:06.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.461958  6163 tablet_service.cc:1467] Processing CreateTablet for tablet da5f80c40aa44e8ca87671c3016767e5 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=e72fd1cc52074fb6b0c83c481e46409d]), partition=RANGE (key) PARTITION UNBOUNDED
03:07:06.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.462786  6409 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet da5f80c40aa44e8ca87671c3016767e5. 1 dirs total, 0 dirs full, 0 dirs failed
03:07:06.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.462980  6163 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet da5f80c40aa44e8ca87671c3016767e5. 1 dirs total, 0 dirs full, 0 dirs failed
03:07:06.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.465389  5928 tablet_service.cc:1467] Processing CreateTablet for tablet da5f80c40aa44e8ca87671c3016767e5 (DEFAULT_TABLE table=TestAuthnTokenReacquireOpen-table [id=e72fd1cc52074fb6b0c83c481e46409d]), partition=RANGE (key) PARTITION UNBOUNDED
03:07:06.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.466384  5928 data_dirs.cc:395] Could only allocate 1 dirs of requested 3 for tablet da5f80c40aa44e8ca87671c3016767e5. 1 dirs total, 0 dirs full, 0 dirs failed
03:07:06.469 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table e72fd1cc52074fb6b0c83c481e46409d
03:07:06.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.471184  6543 tablet_bootstrap.cc:492] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Bootstrap starting.
03:07:06.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.473630  6543 tablet_bootstrap.cc:654] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Neither blocks nor log segments found. Creating new log.
03:07:06.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.473802  6544 tablet_bootstrap.cc:492] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Bootstrap starting.
03:07:06.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.474704  6543 log.cc:824] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Log is configured to *not* fsync() on all Append() calls
03:07:06.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.476171  6544 tablet_bootstrap.cc:654] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Neither blocks nor log segments found. Creating new log.
03:07:06.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.476962  6544 log.cc:824] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Log is configured to *not* fsync() on all Append() calls
03:07:06.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.477201  6546 tablet_bootstrap.cc:492] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Bootstrap starting.
03:07:06.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.477427  6543 tablet_bootstrap.cc:492] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: No bootstrap required, opened a new log
03:07:06.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.477630  6543 ts_tablet_manager.cc:1397] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Time spent bootstrapping tablet: real 0.007s	user 0.005s	sys 0.000s
03:07:06.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.478991  6544 tablet_bootstrap.cc:492] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: No bootstrap required, opened a new log
03:07:06.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.479172  6544 ts_tablet_manager.cc:1397] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Time spent bootstrapping tablet: real 0.006s	user 0.005s	sys 0.000s
03:07:06.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.479558  6546 tablet_bootstrap.cc:654] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Neither blocks nor log segments found. Creating new log.
03:07:06.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.480360  6546 log.cc:824] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Log is configured to *not* fsync() on all Append() calls
03:07:06.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.481379  6514 heartbeater.cc:503] Master 127.5.118.126:36119 was elected leader, sending a full tablet report...
03:07:06.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.482108  6543 raft_consensus.cc:348] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.483112  6543 raft_consensus.cc:374] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
03:07:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.483237  6546 tablet_bootstrap.cc:492] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: No bootstrap required, opened a new log
03:07:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.483400  6543 raft_consensus.cc:729] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: b17565600ba24fcc9e51f441cc5c1a7a, State: Initialized, Role: FOLLOWER
03:07:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.483438  6546 ts_tablet_manager.cc:1397] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Time spent bootstrapping tablet: real 0.008s	user 0.002s	sys 0.002s
03:07:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.483373  6544 raft_consensus.cc:348] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.483605  6544 raft_consensus.cc:374] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
03:07:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.483649  6544 raft_consensus.cc:729] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5c81f9c5db73425586f2d6b4fb54fca1, State: Initialized, Role: FOLLOWER
03:07:06.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.484109  6544 consensus_queue.cc:260] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [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: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.484284  6543 consensus_queue.cc:260] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [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: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.485309  6543 ts_tablet_manager.cc:1428] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Time spent starting tablet: real 0.008s	user 0.001s	sys 0.006s
03:07:06.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.485358  6544 ts_tablet_manager.cc:1428] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Time spent starting tablet: real 0.006s	user 0.005s	sys 0.000s
03:07:06.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.487946  6546 raft_consensus.cc:348] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.488373  6546 raft_consensus.cc:374] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
03:07:06.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.488454  6546 raft_consensus.cc:729] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8276a3e7325b45e09a4716e0c1f47952, State: Initialized, Role: FOLLOWER
03:07:06.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.488916  6546 consensus_queue.cc:260] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [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: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.489952  6546 ts_tablet_manager.cc:1428] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Time spent starting tablet: real 0.006s	user 0.006s	sys 0.001s
03:07:06.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:06.509215  6051 tablet.cc:2369] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Can't schedule compaction. Clean time has not been advanced past its initial value.
03:07:06.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.661480  6555 raft_consensus.cc:482] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
03:07:06.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.661687  6555 raft_consensus.cc:504] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.662817  6555 leader_election.cc:288] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 5c81f9c5db73425586f2d6b4fb54fca1 (127.5.118.65:42281), b17565600ba24fcc9e51f441cc5c1a7a (127.5.118.67:42499)
03:07:06.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.667279  5962 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "da5f80c40aa44e8ca87671c3016767e5" candidate_uuid: "8276a3e7325b45e09a4716e0c1f47952" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" is_pre_election: true
03:07:06.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.667341  6440 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "da5f80c40aa44e8ca87671c3016767e5" candidate_uuid: "8276a3e7325b45e09a4716e0c1f47952" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" is_pre_election: true
03:07:06.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.667586  6440 raft_consensus.cc:2454] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 8276a3e7325b45e09a4716e0c1f47952 in term 0.
03:07:06.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.667606  5962 raft_consensus.cc:2454] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 8276a3e7325b45e09a4716e0c1f47952 in term 0.
03:07:06.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.668041  6097 leader_election.cc:302] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [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: 8276a3e7325b45e09a4716e0c1f47952, b17565600ba24fcc9e51f441cc5c1a7a; no voters: 
03:07:06.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.668290  6555 raft_consensus.cc:2781] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Leader pre-election won for term 1
03:07:06.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.668373  6555 raft_consensus.cc:482] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
03:07:06.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.668416  6555 raft_consensus.cc:3037] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 0 FOLLOWER]: Advancing to term 1
03:07:06.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.669318  6555 raft_consensus.cc:504] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.669620  6555 leader_election.cc:288] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [CANDIDATE]: Term 1 election: Requested vote from peers 5c81f9c5db73425586f2d6b4fb54fca1 (127.5.118.65:42281), b17565600ba24fcc9e51f441cc5c1a7a (127.5.118.67:42499)
03:07:06.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.669966  5962 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "da5f80c40aa44e8ca87671c3016767e5" candidate_uuid: "8276a3e7325b45e09a4716e0c1f47952" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5c81f9c5db73425586f2d6b4fb54fca1"
03:07:06.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.669970  6440 tablet_service.cc:1812] Received RequestConsensusVote() RPC: tablet_id: "da5f80c40aa44e8ca87671c3016767e5" candidate_uuid: "8276a3e7325b45e09a4716e0c1f47952" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b17565600ba24fcc9e51f441cc5c1a7a"
03:07:06.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.670073  6440 raft_consensus.cc:3037] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 0 FOLLOWER]: Advancing to term 1
03:07:06.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.670074  5962 raft_consensus.cc:3037] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 0 FOLLOWER]: Advancing to term 1
03:07:06.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.671017  6440 raft_consensus.cc:2454] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 8276a3e7325b45e09a4716e0c1f47952 in term 1.
03:07:06.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.671247  5962 raft_consensus.cc:2454] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 8276a3e7325b45e09a4716e0c1f47952 in term 1.
03:07:06.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.671427  6097 leader_election.cc:302] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [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: 8276a3e7325b45e09a4716e0c1f47952, b17565600ba24fcc9e51f441cc5c1a7a; no voters: 
03:07:06.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.671728  6555 raft_consensus.cc:2781] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 1 FOLLOWER]: Leader election won for term 1
03:07:06.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.671967  6555 raft_consensus.cc:686] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 1 LEADER]: Becoming Leader. State: Replica: 8276a3e7325b45e09a4716e0c1f47952, State: Running, Role: LEADER
03:07:06.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.672298  6555 consensus_queue.cc:237] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [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: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } }
03:07:06.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:06.675420  5702 catalog_manager.cc:5495] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 reported cstate change: term changed from 0 to 1, leader changed from <none> to 8276a3e7325b45e09a4716e0c1f47952 (127.5.118.66). New cstate: current_term: 1 leader_uuid: "8276a3e7325b45e09a4716e0c1f47952" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "8276a3e7325b45e09a4716e0c1f47952" member_type: VOTER last_known_addr { host: "127.5.118.66" port: 41643 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 } health_report { overall_health: UNKNOWN } } }
03:07:06.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:06.713753  6516 tablet.cc:2369] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Can't schedule compaction. Clean time has not been advanced past its initial value.
03:07:06.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:06.732722  6288 tablet.cc:2369] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Can't schedule compaction. Clean time has not been advanced past its initial value.
03:07:06.759 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table e72fd1cc52074fb6b0c83c481e46409d
03:07:07.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.254778  6563 consensus_queue.cc:1035] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [LEADER]: Connected to new peer: Peer: permanent_uuid: "b17565600ba24fcc9e51f441cc5c1a7a" member_type: VOTER last_known_addr { host: "127.5.118.67" port: 42499 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
03:07:07.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.260995  6567 consensus_queue.cc:1035] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [LEADER]: Connected to new peer: Peer: permanent_uuid: "5c81f9c5db73425586f2d6b4fb54fca1" member_type: VOTER last_known_addr { host: "127.5.118.65" port: 42281 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
03:07:07.763 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table e72fd1cc52074fb6b0c83c481e46409d
03:07:07.785 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet da5f80c40aa44e8ca87671c3016767e5 for table 'TestAuthnTokenReacquireOpen-table' with partition [<start>, <end>)
03:07:07.791 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299993, tablet=da5f80c40aa44e8ca87671c3016767e5@[5c81f9c5db73425586f2d6b4fb54fca1(127.5.118.65:42281),8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)[L],b17565600ba24fcc9e51f441cc5c1a7a(127.5.118.67:42499)]}]
03:07:07.792 [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=15), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.5.118.126:36119, [7ms] Sub RPC GetTableLocations: received response from server master-127.5.118.126:36119: OK, deferred=null)) after lookup
03:07:07.803 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] connecting to peer
03:07:07.805 [DEBUG - kudu-nio-0] (Connection.java:757) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] Successfully connected to peer
03:07:07.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:07.830629  6574 fault_injection.cc:43] FAULT INJECTION ENABLED!
03:07:07.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:07.830709  6574 fault_injection.cc:44] THIS SERVER MAY CRASH!
03:07:07.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260812 03:07:07.831218  6574 negotiation.cc:344] Unauthorized connection attempt: Server connection negotiation failed: server connection from 127.0.0.1:45468: token signing key expired
03:07:07.835 [DEBUG - kudu-nio-0] (Negotiator.java:352) peer /127.5.118.66:41643 sent connection negotiation error: Not authorized: token signing key expired
03:07:07.836 [DEBUG - kudu-nio-0] (Connection.java:239) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] handling channelInactive
03:07:07.836 [DEBUG - kudu-nio-0] (Connection.java:709) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] cleaning up while in state NEGOTIATION_FAILED due to: connection closed
03:07:07.838 [DEBUG - kudu-nio-0] (Connection.java:748) [peer master-127.5.118.126:36119(127.5.118.126:36119)] connecting to peer
03:07:07.841 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.5.118.126:36119(127.5.118.126:36119)] Successfully connected to peer
03:07:07.843 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism GSSAPI chosen for peer 127.5.118.126
03:07:07.869 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x2f474d61, L:/127.0.0.1:46762 - R:/127.5.118.126:36119] using SASL/GSSAPI
03:07:07.872 [INFO - kudu-nio-1] (AsyncKuduClient.java:516) connect to master: received a new authn token
03:07:07.873 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] connecting to peer
03:07:07.874 [DEBUG - kudu-nio-0] (Connection.java:757) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] Successfully connected to peer
03:07:07.889 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xabf8409d, L:/127.0.0.1:45480 - R:/127.5.118.66:41643] using TOKEN/null
03:07:07.915 [DEBUG - kudu-nio-0] (AsyncKuduScanner.java:1374) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 0 rows, more = false) for scanner KuduScanner(table=TestAuthnTokenReacquireOpen-table, tablet=da5f80c40aa44e8ca87671c3016767e5, scannerId=null, scanRequestTimeout=60000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
03:07:07.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.927837  5701 catalog_manager.cc:2461] Servicing SoftDeleteTable request from {username='test-admin'} at 127.0.0.1:46758:
03:07:07.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
03:07:07.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.928130  5701 catalog_manager.cc:2645] Servicing DeleteTable request from {username='test-admin'} at 127.0.0.1:46758:
03:07:07.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) table { table_name: "TestAuthnTokenReacquireOpen-table" }
03:07:07.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.931437  5701 catalog_manager.cc:5782] T 00000000000000000000000000000000 P 18ee3d984fbb4450ab246e808e123c55: Sending DeleteTablet for 3 replicas of tablet da5f80c40aa44e8ca87671c3016767e5
03:07:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.932343  5928 tablet_service.cc:1514] Processing DeleteTablet for tablet da5f80c40aa44e8ca87671c3016767e5 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-12 03:07:07 UTC) from {username='kudu', principal='kudu/127.5.118.126@KRBTEST.COM'} at 127.0.0.1:44334
03:07:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.932548  6163 tablet_service.cc:1514] Processing DeleteTablet for tablet da5f80c40aa44e8ca87671c3016767e5 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-12 03:07:07 UTC) from {username='kudu', principal='kudu/127.5.118.126@KRBTEST.COM'} at 127.0.0.1:45458
03:07:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.932765  6409 tablet_service.cc:1514] Processing DeleteTablet for tablet da5f80c40aa44e8ca87671c3016767e5 with delete_type TABLET_DATA_DELETED (Table deleted at 2026-08-12 03:07:07 UTC) from {username='kudu', principal='kudu/127.5.118.126@KRBTEST.COM'} at 127.0.0.1:54264
03:07:07.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.934297  6587 tablet_replica.cc:330] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: stopping tablet replica
03:07:07.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.934829  6587 raft_consensus.cc:2229] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 1 LEADER]: Raft consensus shutting down.
03:07:07.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.935303  6586 tablet_replica.cc:330] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: stopping tablet replica
03:07:07.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.935375  6587 raft_consensus.cc:2258] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952 [term 1 FOLLOWER]: Raft consensus is shut down!
03:07:07.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.935883  6586 raft_consensus.cc:2229] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 1 FOLLOWER]: Raft consensus shutting down.
03:07:07.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.936334  6586 raft_consensus.cc:2258] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1 [term 1 FOLLOWER]: Raft consensus is shut down!
03:07:07.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.937201  6587 ts_tablet_manager.cc:1905] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Deleting tablet data with delete state TABLET_DATA_DELETED
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.937723  6586 ts_tablet_manager.cc:1905] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Deleting tablet data with delete state TABLET_DATA_DELETED
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.940419  6586 ts_tablet_manager.cc:1918] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.940510  6586 log.cc:1196] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Deleting WAL directory at /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-0/wal/wals/da5f80c40aa44e8ca87671c3016767e5
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.940801  6586 ts_tablet_manager.cc:1939] T da5f80c40aa44e8ca87671c3016767e5 P 5c81f9c5db73425586f2d6b4fb54fca1: Deleting consensus metadata
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.941591  6587 ts_tablet_manager.cc:1918] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.941669  6587 log.cc:1196] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Deleting WAL directory at /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-1/wal/wals/da5f80c40aa44e8ca87671c3016767e5
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.941958  6587 ts_tablet_manager.cc:1939] T da5f80c40aa44e8ca87671c3016767e5 P 8276a3e7325b45e09a4716e0c1f47952: Deleting consensus metadata
03:07:07.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.942121  5671 catalog_manager.cc:4841] TS 5c81f9c5db73425586f2d6b4fb54fca1 (127.5.118.65:42281): tablet da5f80c40aa44e8ca87671c3016767e5 (table TestAuthnTokenReacquireOpen-table [id=e72fd1cc52074fb6b0c83c481e46409d]) successfully deleted
03:07:07.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.943023  5674 catalog_manager.cc:4841] TS 8276a3e7325b45e09a4716e0c1f47952 (127.5.118.66:41643): tablet da5f80c40aa44e8ca87671c3016767e5 (table TestAuthnTokenReacquireOpen-table [id=e72fd1cc52074fb6b0c83c481e46409d]) successfully deleted
03:07:07.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.943444  6589 tablet_replica.cc:330] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: stopping tablet replica
03:07:07.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.943920  6589 raft_consensus.cc:2229] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 1 FOLLOWER]: Raft consensus shutting down.
03:07:07.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.944344  6589 raft_consensus.cc:2258] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a [term 1 FOLLOWER]: Raft consensus is shut down!
03:07:07.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.945775  6589 ts_tablet_manager.cc:1905] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Deleting tablet data with delete state TABLET_DATA_DELETED
03:07:07.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.949100  6589 ts_tablet_manager.cc:1918] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: tablet deleted with delete type TABLET_DATA_DELETED: last-logged OpId 1.1
03:07:07.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.949366  6589 log.cc:1196] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Deleting WAL directory at /tmp/dist-test-taskJGZZI1/test-tmp/mini-kudu-cluster2435168995068657761/ts-2/wal/wals/da5f80c40aa44e8ca87671c3016767e5
03:07:07.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.949867  6589 ts_tablet_manager.cc:1939] T da5f80c40aa44e8ca87671c3016767e5 P b17565600ba24fcc9e51f441cc5c1a7a: Deleting consensus metadata
03:07:07.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.952411  5674 catalog_manager.cc:4841] TS b17565600ba24fcc9e51f441cc5c1a7a (127.5.118.67:42499): tablet da5f80c40aa44e8ca87671c3016767e5 (table TestAuthnTokenReacquireOpen-table [id=e72fd1cc52074fb6b0c83c481e46409d]) successfully deleted
03:07:07.955 [DEBUG - main] (Connection.java:575) [peer master-127.5.118.126:36119(127.5.118.126:36119)] disconnecting while in state READY
03:07:07.956 [DEBUG - main] (Connection.java:575) [peer master-127.5.118.126:36119(127.5.118.126:36119)] disconnecting while in state READY
03:07:07.956 [DEBUG - main] (Connection.java:575) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] disconnecting while in state READY
03:07:07.958 [DEBUG - kudu-nio-0] (Connection.java:239) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] handling channelInactive
03:07:07.959 [DEBUG - kudu-nio-0] (Connection.java:709) [peer 8276a3e7325b45e09a4716e0c1f47952(127.5.118.66:41643)] cleaning up while in state READY due to: connection closed
03:07:07.961 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2704) Releasing all remaining resources
03:07:07.965 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.5.118.126:36119(127.5.118.126:36119)] handling channelInactive
03:07:07.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:07.965967  5593 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu with pid 5815
03:07:08.005 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.5.118.126:36119(127.5.118.126:36119)] cleaning up while in state READY due to: connection closed
03:07:08.005 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.5.118.126:36119(127.5.118.126:36119)] handling channelInactive
03:07:08.006 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.5.118.126:36119(127.5.118.126:36119)] cleaning up while in state READY due to: connection closed
03:07:08.012 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:08.012704  5593 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu with pid 6059
03:07:08.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:08.028995  5593 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu with pid 6297
03:07:08.041 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260812 03:07:08.041285  5593 external_mini_cluster.cc:1595] Killing /tmp/dist-test-taskJGZZI1/build/debug/bin/kudu with pid 5642
03:07:08.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-08-12T03:07:08Z chronyd exiting

Time: 5.523

OK (1 test)

