JUnit version 4.13.2
.14:03:56.387 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1779113036378
14:03:56.445 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
14:03:56.533 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
14:03:56.548 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-task1Tz940/test-tmp
14:03:56.556 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/debug/bin
14:03:56.560 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-task1Tz940/java/build/dist-test/../../../build/debug/bin/kudu, test, mini_cluster, --serialization=pb]
14:03:56.754 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

14:03:56.769 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:03:56.770 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

14:03:56.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.771656  9566 mini_oidc.cc:150] Starting JWKS server
14:03:56.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.773589  9566 webserver.cc:533] Webserver started at http://127.0.0.1:37445/ using document root <none> and password file <none>
14:03:56.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.773787  9566 mini_oidc.cc:186] Starting OIDC Discovery server
14:03:56.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.774081  9566 webserver.cc:533] Webserver started at http://127.0.0.1:34037/ using document root <none> and password file <none>
14:03:56.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:03:56Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
14:03:56.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:03:56Z Disabled control of system clock
14:03:56.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.789047  9566 external_mini_cluster.cc:1366] Running /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:56.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:56.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/wal
14:03:56.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data
14:03:56.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:03:56.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:03:56.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:03:56.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:56.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:56.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:56.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/logs
14:03:56.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data/info.pb
14:03:56.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:56.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:03:56.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:03:56.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:03:56.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:03:56.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:56.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:56.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:56.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:03:56.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:03:56.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:03:56.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:03:56.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.190:46813
14:03:56.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.190
14:03:56.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:56.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:56.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:56.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:56.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:56.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:03:56.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:56.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:03:56.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37445/jwks.json with env {}
14:03:56.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.896000  9577 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:03:56.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.896252  9577 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:03:56.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.896312  9577 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:03:56.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.900158  9577 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:03:56.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.900233  9577 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:03:56.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.900261  9577 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:03:56.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.900285  9577 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:03:56.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.900306  9577 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:03:56.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.900326  9577 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37445/jwks.json
14:03:56.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.904886  9577 master_runner.cc:387] Master server non-default flags:
14:03:56.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:56.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:56.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:56.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:56.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data
14:03:56.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/wal
14:03:56.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:03:56.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:56.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:56.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:56.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:03:56.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.190:46813
14:03:56.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:03:56.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:03:56.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:03:56.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37445/jwks.json
14:03:56.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:56.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:56.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data/info.pb
14:03:56.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.190
14:03:56.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:56.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:03:56.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.9577
14:03:56.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:56.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:03:56.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:03:56.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:56.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/logs
14:03:56.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:56.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:03:56.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:03:56.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:03:56.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:03:56.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:03:56.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
14:03:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:14 UTC on bdcb31816ec0
14:03:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11956
14:03:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.906463  9577 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:03:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.908020  9577 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:03:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.915081  9583 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
14:03:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.915094  9585 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
14:03:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.915150  9582 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
14:03:56.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.916090  9577 server_base.cc:1061] running on GCE node
14:03:56.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.916705  9577 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:03:56.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.917744  9577 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:03:56.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.918915  9577 hybrid_clock.cc:648] HybridClock initialized: now 1779113036918898 us; error 32 us; skew 500 ppm
14:03:56.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.921039  9577 webserver.cc:533] Webserver started at http://127.9.87.190:40115/ using document root <none> and password file <none>
14:03:56.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.921691  9577 fs_manager.cc:362] Metadata directory not provided
14:03:56.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.921756  9577 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:03:56.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.921993  9577 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:03:56.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.923796  9577 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data/instance:
14:03:56.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "863c4805bc5748158d72d29381a3d719"
14:03:56.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:56 on dist-test-slave-btpw"
14:03:56.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.924331  9577 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/wal/instance:
14:03:56.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "863c4805bc5748158d72d29381a3d719"
14:03:56.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:56 on dist-test-slave-btpw"
14:03:56.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.928242  9577 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.004s	sys 0.000s
14:03:56.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.930581  9591 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:03:56.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.931694  9577 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
14:03:56.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.931814  9577 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data,/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/wal
14:03:56.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "863c4805bc5748158d72d29381a3d719"
14:03:56.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:56 on dist-test-slave-btpw"
14:03:56.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.931916  9577 fs_report.cc:389] FS layout report
14:03:56.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:03:56.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/wal
14:03:56.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/wal
14:03:56.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data/data
14:03:56.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:03:56.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:03:56.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:03:56.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:03:56.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:03:56.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:03:56.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:03:56.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:03:56.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:03:56.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:03:56.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:03:56.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.951267  9577 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:03:56.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.952185  9577 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:03:56.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.952395  9577 kserver.cc:163] Server-wide thread pool size limit: 3276
14:03:56.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.959587  9643 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.9.87.190:46813 every 8 connection(s)
14:03:56.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.959587  9577 rpc_server.cc:307] RPC server started. Bound to: 127.9.87.190:46813
14:03:56.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.960945  9577 server_base.cc:1193] Dumped server information to /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/data/info.pb
14:03:56.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.964622  9644 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:03:56.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.966964  9566 external_mini_cluster.cc:1428] Started /tmp/dist-test-task1Tz940/build/debug/bin/kudu as pid 9577
14:03:56.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.967118  9566 external_mini_cluster.cc:1442] Reading /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-0/wal/instance
14:03:56.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.968693  9566 external_mini_cluster.cc:1366] Running /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:56.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:56.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/wal
14:03:56.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data
14:03:56.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:03:56.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:03:56.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:03:56.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:56.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:56.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:56.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/logs
14:03:56.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data/info.pb
14:03:56.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:56.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:03:56.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:03:56.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:03:56.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:03:56.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:56.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:56.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:56.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:03:56.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:03:56.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:03:56.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:03:56.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.189:46159
14:03:56.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.189
14:03:56.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:56.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:56.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:56.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:56.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:56.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:03:56.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:56.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:03:56.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37445/jwks.json with env {}
14:03:56.975 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.968304  9644 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } has no permanent_uuid. Determining permanent_uuid...
14:03:56.982 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:56.982092  9644 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } has no permanent_uuid. Determining permanent_uuid...
14:03:56.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.982882  9595 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.9.87.189:46159: connect: Connection refused (error 111)
14:03:56.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:56.983711  9644 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.189:46159: Network error: Client connection negotiation failed: client connection to 127.9.87.189:46159: connect: Connection refused (error 111)
14:03:57.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.032955  9644 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } attempt: 1
14:03:57.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.034317  9644 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.189:46159: Network error: Client connection negotiation failed: client connection to 127.9.87.189:46159: connect: Connection refused (error 111)
14:03:57.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.073184  9646 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:03:57.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.073457  9646 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:03:57.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.073519  9646 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:03:57.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.077307  9646 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:03:57.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.077385  9646 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:03:57.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.077425  9646 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:03:57.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.077451  9646 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:03:57.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.077474  9646 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:03:57.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.077493  9646 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37445/jwks.json
14:03:57.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.082041  9646 master_runner.cc:387] Master server non-default flags:
14:03:57.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:57.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data
14:03:57.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/wal
14:03:57.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:03:57.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:03:57.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.189:46159
14:03:57.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:03:57.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:03:57.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:03:57.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37445/jwks.json
14:03:57.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data/info.pb
14:03:57.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.189
14:03:57.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:03:57.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.9646
14:03:57.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:03:57.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:03:57.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/logs
14:03:57.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:03:57.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:03:57.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:03:57.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:03:57.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:03:57.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
14:03:57.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:14 UTC on bdcb31816ec0
14:03:57.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11956
14:03:57.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.083673  9646 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:03:57.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.085331  9646 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:03:57.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.092425  9654 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
14:03:57.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.092545  9657 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
14:03:57.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.092434  9655 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
14:03:57.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.093683  9646 server_base.cc:1061] running on GCE node
14:03:57.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.094466  9646 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:03:57.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.095597  9646 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:03:57.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.096774  9646 hybrid_clock.cc:648] HybridClock initialized: now 1779113037096761 us; error 40 us; skew 500 ppm
14:03:57.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.098791  9646 webserver.cc:533] Webserver started at http://127.9.87.189:39939/ using document root <none> and password file <none>
14:03:57.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.099524  9646 fs_manager.cc:362] Metadata directory not provided
14:03:57.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.099596  9646 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:03:57.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.099850  9646 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:03:57.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.101620  9646 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data/instance:
14:03:57.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c94563bc0fbc4e0ca8a733cd965345a5"
14:03:57.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.102157  9646 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/wal/instance:
14:03:57.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c94563bc0fbc4e0ca8a733cd965345a5"
14:03:57.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.103163  9644 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } attempt: 2
14:03:57.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.104873  9644 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.189:46159: Network error: Client connection negotiation failed: client connection to 127.9.87.189:46159: connect: Connection refused (error 111)
14:03:57.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.106417  9646 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.003s	sys 0.004s
14:03:57.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.108945  9663 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:03:57.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.110308  9646 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.001s	sys 0.000s
14:03:57.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.110442  9646 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data,/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/wal
14:03:57.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c94563bc0fbc4e0ca8a733cd965345a5"
14:03:57.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.110555  9646 fs_report.cc:389] FS layout report
14:03:57.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:03:57.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/wal
14:03:57.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/wal
14:03:57.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data/data
14:03:57.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:03:57.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:03:57.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:03:57.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:03:57.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:03:57.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:03:57.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:03:57.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:03:57.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:03:57.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:03:57.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:03:57.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.124292  9646 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:03:57.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.125304  9646 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:03:57.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.125514  9646 kserver.cc:163] Server-wide thread pool size limit: 3276
14:03:57.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.133509  9715 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.9.87.189:46159 every 8 connection(s)
14:03:57.134 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.133510  9646 rpc_server.cc:307] RPC server started. Bound to: 127.9.87.189:46159
14:03:57.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.134845  9646 server_base.cc:1193] Dumped server information to /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/data/info.pb
14:03:57.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.135250  9566 external_mini_cluster.cc:1428] Started /tmp/dist-test-task1Tz940/build/debug/bin/kudu as pid 9646
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.135373  9566 external_mini_cluster.cc:1442] Reading /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-1/wal/instance
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.136066  9566 external_mini_cluster.cc:1366] Running /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/wal
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/logs
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data/info.pb
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:03:57.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:03:57.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:03:57.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:03:57.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:03:57.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.188:36479
14:03:57.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.188
14:03:57.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:57.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:03:57.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:03:57.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37445/jwks.json with env {}
14:03:57.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.140291  9716 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:03:57.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.146711  9716 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } has no permanent_uuid. Determining permanent_uuid...
14:03:57.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.156975  9716 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } has no permanent_uuid. Determining permanent_uuid...
14:03:57.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.162757  9716 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } has no permanent_uuid. Determining permanent_uuid...
14:03:57.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.163607  9665 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.9.87.188:36479: connect: Connection refused (error 111)
14:03:57.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.164718  9716 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.188:36479: Network error: Client connection negotiation failed: client connection to 127.9.87.188:36479: connect: Connection refused (error 111)
14:03:57.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.196041  9644 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } attempt: 3
14:03:57.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.200593  9644 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } has no permanent_uuid. Determining permanent_uuid...
14:03:57.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.201964  9644 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.188:36479: Network error: Client connection negotiation failed: client connection to 127.9.87.188:36479: connect: Connection refused (error 111)
14:03:57.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.213966  9716 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } attempt: 1
14:03:57.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.215706  9716 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.188:36479: Network error: Client connection negotiation failed: client connection to 127.9.87.188:36479: connect: Connection refused (error 111)
14:03:57.233 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.233135  9644 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } attempt: 1
14:03:57.234 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.234543  9644 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.188:36479: Network error: Client connection negotiation failed: client connection to 127.9.87.188:36479: connect: Connection refused (error 111)
14:03:57.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.248273  9718 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:03:57.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.248519  9718 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:03:57.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.248564  9718 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:03:57.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.252044  9718 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:03:57.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.252116  9718 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:03:57.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.252136  9718 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:03:57.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.252153  9718 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:03:57.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.252169  9718 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:03:57.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.252182  9718 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37445/jwks.json
14:03:57.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.256289  9718 master_runner.cc:387] Master server non-default flags:
14:03:57.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/wal
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.188:36479
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37445/jwks.json
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data/info.pb
14:03:57.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.188
14:03:57.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:03:57.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.9718
14:03:57.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:03:57.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:03:57.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/logs
14:03:57.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:03:57.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:03:57.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:03:57.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:03:57.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:03:57.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
14:03:57.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:14 UTC on bdcb31816ec0
14:03:57.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11956
14:03:57.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.257422  9718 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:03:57.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.258756  9718 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:03:57.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.265756  9726 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
14:03:57.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.265756  9727 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
14:03:57.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.265801  9729 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
14:03:57.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.266009  9718 server_base.cc:1061] running on GCE node
14:03:57.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.267930  9718 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:03:57.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.269204  9718 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:03:57.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.270396  9718 hybrid_clock.cc:648] HybridClock initialized: now 1779113037270339 us; error 84 us; skew 500 ppm
14:03:57.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.272820  9718 webserver.cc:533] Webserver started at http://127.9.87.188:45645/ using document root <none> and password file <none>
14:03:57.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.273563  9718 fs_manager.cc:362] Metadata directory not provided
14:03:57.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.273653  9718 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:03:57.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.273890  9718 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:03:57.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.276152  9718 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data/instance:
14:03:57.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "10e311f249d84b0a8234241898c9c3df"
14:03:57.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.276752  9718 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/wal/instance:
14:03:57.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "10e311f249d84b0a8234241898c9c3df"
14:03:57.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.280841  9718 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.001s	sys 0.004s
14:03:57.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.283202  9735 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:03:57.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.283890  9716 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } attempt: 2
14:03:57.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.284420  9718 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.001s
14:03:57.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.284536  9718 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data,/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/wal
14:03:57.284 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "10e311f249d84b0a8234241898c9c3df"
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.284641  9718 fs_report.cc:389] FS layout report
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/wal
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/wal
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data/data
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:03:57.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:03:57.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:03:57.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:03:57.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:03:57.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:03:57.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:03:57.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.285185  9716 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.9.87.188:36479: Network error: Client connection negotiation failed: client connection to 127.9.87.188:36479: connect: Connection refused (error 111)
14:03:57.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.298316  9718 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:03:57.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.299088  9718 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:03:57.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.299278  9718 kserver.cc:163] Server-wide thread pool size limit: 3276
14:03:57.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.306159  9718 rpc_server.cc:307] RPC server started. Bound to: 127.9.87.188:36479
14:03:57.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.306198  9787 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.9.87.188:36479 every 8 connection(s)
14:03:57.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.307339  9718 server_base.cc:1193] Dumped server information to /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/data/info.pb
14:03:57.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.309754  9644 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } attempt: 2
14:03:57.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.310248  9788 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:03:57.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.311836  9566 external_mini_cluster.cc:1428] Started /tmp/dist-test-task1Tz940/build/debug/bin/kudu as pid 9718
14:03:57.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.311935  9566 external_mini_cluster.cc:1442] Reading /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/master-2/wal/instance
14:03:57.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.315372  9788 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } has no permanent_uuid. Determining permanent_uuid...
14:03:57.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.321981  9644 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: Bootstrap starting.
14:03:57.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.324762  9644 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: Neither blocks nor log segments found. Creating new log.
14:03:57.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.326432  9788 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } has no permanent_uuid. Determining permanent_uuid...
14:03:57.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.326702  9644 log.cc:826] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: Log is configured to *not* fsync() on all Append() calls
14:03:57.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.328644  9644 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: No bootstrap required, opened a new log
14:03:57.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.331859  9644 raft_consensus.cc:359] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.332000  9788 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } has no permanent_uuid. Determining permanent_uuid...
14:03:57.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.332086  9644 raft_consensus.cc:385] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:03:57.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.332149  9644 raft_consensus.cc:740] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 863c4805bc5748158d72d29381a3d719, State: Initialized, Role: FOLLOWER
14:03:57.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.332677  9644 consensus_queue.cc:260] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [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: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.334002  9796 sys_catalog.cc:455] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.334137  9796 sys_catalog.cc:458] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [sys.catalog]: This master's current role is: FOLLOWER
14:03:57.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.334079  9644 sys_catalog.cc:565] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [sys.catalog]: configured and running, proceeding with master startup.
14:03:57.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.339010  9788 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df: Bootstrap starting.
14:03:57.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.339903  9807 catalog_manager.cc:1593] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:03:57.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.339970  9807 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:03:57.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.341671  9788 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df: Neither blocks nor log segments found. Creating new log.
14:03:57.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.340040  9577 master_runner.cc:187] Error getting master registration for 127.9.87.189:46159: OK, instance_id { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" instance_seqno: 1779113037131344 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:03:57.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.342286  9577 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:03:57.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.342542  9788 log.cc:826] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df: Log is configured to *not* fsync() on all Append() calls
14:03:57.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.346158  9788 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df: No bootstrap required, opened a new log
14:03:57.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.349593  9788 raft_consensus.cc:359] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.351003  9788 raft_consensus.cc:385] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:03:57.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.351100  9788 raft_consensus.cc:740] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 10e311f249d84b0a8234241898c9c3df, State: Initialized, Role: FOLLOWER
14:03:57.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.351701  9788 consensus_queue.cc:260] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [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: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.352810  9810 sys_catalog.cc:455] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.353089  9788 sys_catalog.cc:565] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: configured and running, proceeding with master startup.
14:03:57.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.353209  9810 sys_catalog.cc:458] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: This master's current role is: FOLLOWER
14:03:57.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.358978  9821 catalog_manager.cc:1593] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:03:57.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.359606  9821 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:03:57.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.359295  9718 master_runner.cc:187] Error getting master registration for 127.9.87.189:46159: OK, instance_id { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" instance_seqno: 1779113037131344 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:03:57.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.361086  9718 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:03:57.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.376454  9716 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } attempt: 3
14:03:57.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.382997  9716 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: Bootstrap starting.
14:03:57.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.385838  9716 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: Neither blocks nor log segments found. Creating new log.
14:03:57.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.386750  9716 log.cc:826] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: Log is configured to *not* fsync() on all Append() calls
14:03:57.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.388831  9716 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: No bootstrap required, opened a new log
14:03:57.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.392911  9716 raft_consensus.cc:359] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.393128  9716 raft_consensus.cc:385] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:03:57.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.393178  9716 raft_consensus.cc:740] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c94563bc0fbc4e0ca8a733cd965345a5, State: Initialized, Role: FOLLOWER
14:03:57.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.393718  9716 consensus_queue.cc:260] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [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: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.394309  9824 sys_catalog.cc:455] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [sys.catalog]: SysCatalogTable state changed. Reason: RaftConsensus started. Latest consensus state: current_term: 0 committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.394819  9824 sys_catalog.cc:458] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [sys.catalog]: This master's current role is: FOLLOWER
14:03:57.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.396723  9716 sys_catalog.cc:565] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [sys.catalog]: configured and running, proceeding with master startup.
14:03:57.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.397315  9810 raft_consensus.cc:493] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:03:57.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.397500  9810 raft_consensus.cc:515] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.398473  9810 leader_election.cc:290] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 863c4805bc5748158d72d29381a3d719 (127.9.87.190:46813), c94563bc0fbc4e0ca8a733cd965345a5 (127.9.87.189:46159)
14:03:57.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.399338  9618 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "10e311f249d84b0a8234241898c9c3df" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "863c4805bc5748158d72d29381a3d719" is_pre_election: true
14:03:57.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.399616  9618 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 10e311f249d84b0a8234241898c9c3df in term 0.
14:03:57.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.400604  9736 leader_election.cc:304] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [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: 10e311f249d84b0a8234241898c9c3df, 863c4805bc5748158d72d29381a3d719; no voters: 
14:03:57.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.402913  9835 catalog_manager.cc:1593] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:03:57.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.400022  9690 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "10e311f249d84b0a8234241898c9c3df" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" is_pre_election: true
14:03:57.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.403478  9690 raft_consensus.cc:2468] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 10e311f249d84b0a8234241898c9c3df in term 0.
14:03:57.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.404122  9835 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:03:57.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.407987  9646 master_runner.cc:264] No leader master found from master c94563bc0fbc4e0ca8a733cd965345a5
14:03:57.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.408061  9646 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:03:57.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.416169  9810 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Leader pre-election won for term 1
14:03:57.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.416306  9810 raft_consensus.cc:493] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:03:57.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.416349  9810 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 0 FOLLOWER]: Advancing to term 1
14:03:57.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.417573  9810 raft_consensus.cc:515] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.418042  9810 leader_election.cc:290] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [CANDIDATE]: Term 1 election: Requested vote from peers 863c4805bc5748158d72d29381a3d719 (127.9.87.190:46813), c94563bc0fbc4e0ca8a733cd965345a5 (127.9.87.189:46159)
14:03:57.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.418628  9690 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "10e311f249d84b0a8234241898c9c3df" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "c94563bc0fbc4e0ca8a733cd965345a5"
14:03:57.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.418759  9690 raft_consensus.cc:3060] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [term 0 FOLLOWER]: Advancing to term 1
14:03:57.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.419968  9690 raft_consensus.cc:2468] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 10e311f249d84b0a8234241898c9c3df in term 1.
14:03:57.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.423446  9739 leader_election.cc:304] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [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: 10e311f249d84b0a8234241898c9c3df, c94563bc0fbc4e0ca8a733cd965345a5; no voters: 
14:03:57.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.423971  9618 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "10e311f249d84b0a8234241898c9c3df" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "863c4805bc5748158d72d29381a3d719"
14:03:57.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.424124  9618 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [term 0 FOLLOWER]: Advancing to term 1
14:03:57.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.425341  9618 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 10e311f249d84b0a8234241898c9c3df in term 1.
14:03:57.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.429852  9810 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 1 FOLLOWER]: Leader election won for term 1
14:03:57.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.430598  9810 raft_consensus.cc:697] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [term 1 LEADER]: Becoming Leader. State: Replica: 10e311f249d84b0a8234241898c9c3df, State: Running, Role: LEADER
14:03:57.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.430965  9810 consensus_queue.cc:237] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [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: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } }
14:03:57.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.434046  9810 sys_catalog.cc:455] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: SysCatalogTable state changed. Reason: New leader 10e311f249d84b0a8234241898c9c3df. Latest consensus state: current_term: 1 leader_uuid: "10e311f249d84b0a8234241898c9c3df" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.434182  9810 sys_catalog.cc:458] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: This master's current role is: LEADER
14:03:57.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.442574  9839 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
14:03:57.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.449841  9839 catalog_manager.cc:1519] Initializing Kudu cluster ID...
14:03:57.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.453953  9597 tablet.cc:2404] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:03:57.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.471338  9690 raft_consensus.cc:1275] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [term 1 FOLLOWER]: Refusing update from remote peer 10e311f249d84b0a8234241898c9c3df: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:03:57.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.477439  9837 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [LEADER]: Connected to new peer: Peer: permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:03:57.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.483597  9824 sys_catalog.cc:455] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 10e311f249d84b0a8234241898c9c3df. Latest consensus state: current_term: 1 leader_uuid: "10e311f249d84b0a8234241898c9c3df" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.483744  9824 sys_catalog.cc:458] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [sys.catalog]: This master's current role is: FOLLOWER
14:03:57.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.488729  9618 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [term 1 FOLLOWER]: Refusing update from remote peer 10e311f249d84b0a8234241898c9c3df: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:03:57.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.489495  9837 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [LEADER]: Connected to new peer: Peer: permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:03:57.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.493933  9796 sys_catalog.cc:455] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 10e311f249d84b0a8234241898c9c3df. Latest consensus state: current_term: 1 leader_uuid: "10e311f249d84b0a8234241898c9c3df" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.494062  9796 sys_catalog.cc:458] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [sys.catalog]: This master's current role is: FOLLOWER
14:03:57.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.507074  9837 sys_catalog.cc:455] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "10e311f249d84b0a8234241898c9c3df" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.507234  9837 sys_catalog.cc:458] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: This master's current role is: LEADER
14:03:57.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.510392  9796 sys_catalog.cc:455] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "10e311f249d84b0a8234241898c9c3df" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.510547  9796 sys_catalog.cc:458] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719 [sys.catalog]: This master's current role is: FOLLOWER
14:03:57.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.511924  9840 mvcc.cc:204] Tried to move back new op lower bound from 7287247001473818624 to 7287247001325432832. Current Snapshot: MvccSnapshot[applied={T|T < 7287247001473818624}]
14:03:57.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.516374  9810 sys_catalog.cc:455] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "10e311f249d84b0a8234241898c9c3df" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.516523  9810 sys_catalog.cc:458] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df [sys.catalog]: This master's current role is: LEADER
14:03:57.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.518966  9824 sys_catalog.cc:455] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "10e311f249d84b0a8234241898c9c3df" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "863c4805bc5748158d72d29381a3d719" member_type: VOTER last_known_addr { host: "127.9.87.190" port: 46813 } } peers { permanent_uuid: "c94563bc0fbc4e0ca8a733cd965345a5" member_type: VOTER last_known_addr { host: "127.9.87.189" port: 46159 } } peers { permanent_uuid: "10e311f249d84b0a8234241898c9c3df" member_type: VOTER last_known_addr { host: "127.9.87.188" port: 36479 } } }
14:03:57.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.519086  9824 sys_catalog.cc:458] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5 [sys.catalog]: This master's current role is: FOLLOWER
14:03:57.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.520514  9843 mvcc.cc:204] Tried to move back new op lower bound from 7287247001473818624 to 7287247001325432832. Current Snapshot: MvccSnapshot[applied={T|T < 7287247001473818624}]
14:03:57.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.520996  9841 mvcc.cc:204] Tried to move back new op lower bound from 7287247001473818624 to 7287247001325432832. Current Snapshot: MvccSnapshot[applied={T|T < 7287247001473818624}]
14:03:57.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.526255  9839 catalog_manager.cc:1382] Generated new cluster ID: ed6e5ad0991742639a253668870bd6e9
14:03:57.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.526336  9839 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
14:03:57.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.550501  9839 catalog_manager.cc:1405] Generated new certificate authority record
14:03:57.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.552302  9839 catalog_manager.cc:1539] Loading token signing keys...
14:03:57.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.570379  9839 catalog_manager.cc:6069] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df: Generated new TSK 0
14:03:57.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.571559  9839 catalog_manager.cc:1549] Initializing in-progress tserver states...
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.581198  9566 external_mini_cluster.cc:1366] Running /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/wal
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/logs
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data/info.pb
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.129:0
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.9.87.129
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.129
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:03:57.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.691720  9848 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:03:57.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.691993  9848 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:03:57.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.692041  9848 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:03:57.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.695859  9848 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:03:57.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.695981  9848 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.9.87.129
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.700201  9848 tablet_server_runner.cc:78] Tablet server non-default flags:
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/wal
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.129:0
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data/info.pb
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.129
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.9848
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.9.87.129
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/logs
14:03:57.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:14 UTC on bdcb31816ec0
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11956
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.701333  9848 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:03:57.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.702409  9848 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:03:57.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.708993  9856 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
14:03:57.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.709079  9854 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
14:03:57.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.709203  9853 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
14:03:57.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.709558  9848 server_base.cc:1061] running on GCE node
14:03:57.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.710350  9848 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:03:57.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.711076  9848 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:03:57.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.712275  9848 hybrid_clock.cc:648] HybridClock initialized: now 1779113037712246 us; error 48 us; skew 500 ppm
14:03:57.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.714695  9848 webserver.cc:533] Webserver started at http://127.9.87.129:41091/ using document root <none> and password file <none>
14:03:57.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.715495  9848 fs_manager.cc:362] Metadata directory not provided
14:03:57.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.715580  9848 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:03:57.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.715807  9848 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:03:57.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.717957  9848 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data/instance:
14:03:57.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0bb5761dc13047a095f57ede85a7dd7c"
14:03:57.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.718602  9848 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/wal/instance:
14:03:57.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0bb5761dc13047a095f57ede85a7dd7c"
14:03:57.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.723062  9848 fs_manager.cc:696] Time spent creating directory manager: real 0.004s	user 0.003s	sys 0.004s
14:03:57.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.725919  9862 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.727172  9848 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.000s
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.727300  9848 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data,/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/wal
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0bb5761dc13047a095f57ede85a7dd7c"
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.727449  9848 fs_report.cc:389] FS layout report
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/wal
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/wal
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data/data
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:03:57.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:03:57.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:03:57.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.753093  9848 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:03:57.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.753798  9848 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:03:57.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.753957  9848 kserver.cc:163] Server-wide thread pool size limit: 3276
14:03:57.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.754549  9848 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:03:57.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.755697  9848 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:03:57.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.755748  9848 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:03:57.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.755787  9848 ts_tablet_manager.cc:616] Registered 0 tablets
14:03:57.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.755800  9848 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:03:57.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.764775  9848 rpc_server.cc:307] RPC server started. Bound to: 127.9.87.129:35987
14:03:57.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.764803  9975 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.9.87.129:35987 every 8 connection(s)
14:03:57.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.765761  9848 server_base.cc:1193] Dumped server information to /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/data/info.pb
14:03:57.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.769989  9566 external_mini_cluster.cc:1428] Started /tmp/dist-test-task1Tz940/build/debug/bin/kudu as pid 9848
14:03:57.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.770099  9566 external_mini_cluster.cc:1442] Reading /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-0/wal/instance
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.774812  9566 external_mini_cluster.cc:1366] Running /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/wal
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/logs
14:03:57.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data/info.pb
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:03:57.776 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.130:0
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.9.87.130
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.130
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.775331  9978 heartbeater.cc:344] Connected to a master server at 127.9.87.189:46159
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.775370  9977 heartbeater.cc:344] Connected to a master server at 127.9.87.190:46813
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.775585  9977 heartbeater.cc:461] Registering TS with master...
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.775583  9978 heartbeater.cc:461] Registering TS with master...
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.776237  9978 heartbeater.cc:507] Master 127.9.87.189:46159 requested a full tablet report, sending...
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.776610  9976 heartbeater.cc:344] Connected to a master server at 127.9.87.188:36479
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.776674  9976 heartbeater.cc:461] Registering TS with master...
14:03:57.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.776237  9977 heartbeater.cc:507] Master 127.9.87.190:46813 requested a full tablet report, sending...
14:03:57.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.776827  9976 heartbeater.cc:507] Master 127.9.87.188:36479 requested a full tablet report, sending...
14:03:57.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.777707  9752 ts_manager.cc:194] Registered new tserver with Master: 0bb5761dc13047a095f57ede85a7dd7c (127.9.87.129:35987)
14:03:57.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.777707  9607 ts_manager.cc:194] Registered new tserver with Master: 0bb5761dc13047a095f57ede85a7dd7c (127.9.87.129:35987)
14:03:57.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.777751  9679 ts_manager.cc:194] Registered new tserver with Master: 0bb5761dc13047a095f57ede85a7dd7c (127.9.87.129:35987)
14:03:57.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.778980  9752 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.9.87.129:44047
14:03:57.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.881472  9983 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:03:57.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.881711  9983 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:03:57.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.881752  9983 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:03:57.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.885167  9983 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:03:57.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.885286  9983 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.9.87.130
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.889221  9983 tablet_server_runner.cc:78] Tablet server non-default flags:
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/wal
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.130:0
14:03:57.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data/info.pb
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.130
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.9983
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.9.87.130
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/logs
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:14 UTC on bdcb31816ec0
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11956
14:03:57.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.890394  9983 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:03:57.891 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.891546  9983 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:03:57.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.898336  9983 server_base.cc:1061] running on GCE node
14:03:57.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.898236  9988 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
14:03:57.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.898231  9989 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
14:03:57.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:57.898277  9991 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
14:03:57.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.898993  9983 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:03:57.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.899786  9983 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:03:57.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.901149  9983 hybrid_clock.cc:648] HybridClock initialized: now 1779113037901131 us; error 41 us; skew 500 ppm
14:03:57.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.903033  9983 webserver.cc:533] Webserver started at http://127.9.87.130:43479/ using document root <none> and password file <none>
14:03:57.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.903676  9983 fs_manager.cc:362] Metadata directory not provided
14:03:57.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.903740  9983 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:03:57.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.903993  9983 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:03:57.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.905671  9983 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data/instance:
14:03:57.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e42fbded00044b188d453144d5c6f646"
14:03:57.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.906208  9983 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/wal/instance:
14:03:57.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e42fbded00044b188d453144d5c6f646"
14:03:57.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.909852  9983 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.006s	sys 0.000s
14:03:57.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.912262  9997 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:03:57.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.913347  9983 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.000s	sys 0.003s
14:03:57.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.913456  9983 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data,/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/wal
14:03:57.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "e42fbded00044b188d453144d5c6f646"
14:03:57.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:57 on dist-test-slave-btpw"
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.913537  9983 fs_report.cc:389] FS layout report
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/wal
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/wal
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data/data
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:03:57.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:03:57.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:03:57.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.933426  9983 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:03:57.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.934151  9983 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:03:57.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.934305  9983 kserver.cc:163] Server-wide thread pool size limit: 3276
14:03:57.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.934810  9983 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:03:57.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.935922  9983 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:03:57.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.935971  9983 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:03:57.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.936009  9983 ts_tablet_manager.cc:616] Registered 0 tablets
14:03:57.936 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.936023  9983 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:03:57.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.945134  9983 rpc_server.cc:307] RPC server started. Bound to: 127.9.87.130:39799
14:03:57.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.945155 10110 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.9.87.130:39799 every 8 connection(s)
14:03:57.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.946111  9983 server_base.cc:1193] Dumped server information to /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/data/info.pb
14:03:57.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.953168  9566 external_mini_cluster.cc:1428] Started /tmp/dist-test-task1Tz940/build/debug/bin/kudu as pid 9983
14:03:57.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.953279  9566 external_mini_cluster.cc:1442] Reading /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-1/wal/instance
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.955816 10111 heartbeater.cc:344] Connected to a master server at 127.9.87.188:36479
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.955844 10112 heartbeater.cc:344] Connected to a master server at 127.9.87.190:46813
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.956090 10111 heartbeater.cc:461] Registering TS with master...
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.956091 10112 heartbeater.cc:461] Registering TS with master...
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.956431  9566 external_mini_cluster.cc:1366] Running /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task1Tz940/build/debug/bin/kudu
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/wal
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:57.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/logs
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data/info.pb
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.131:0
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.9.87.131
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.131
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.956779 10112 heartbeater.cc:507] Master 127.9.87.190:46813 requested a full tablet report, sending...
14:03:57.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.956779 10111 heartbeater.cc:507] Master 127.9.87.188:36479 requested a full tablet report, sending...
14:03:57.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.957984  9603 ts_manager.cc:194] Registered new tserver with Master: e42fbded00044b188d453144d5c6f646 (127.9.87.130:39799)
14:03:57.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.958453  9752 ts_manager.cc:194] Registered new tserver with Master: e42fbded00044b188d453144d5c6f646 (127.9.87.130:39799)
14:03:57.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.959033  9752 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.9.87.130:49635
14:03:57.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.959650 10113 heartbeater.cc:344] Connected to a master server at 127.9.87.189:46159
14:03:57.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.959730 10113 heartbeater.cc:461] Registering TS with master...
14:03:57.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.960119 10113 heartbeater.cc:507] Master 127.9.87.189:46159 requested a full tablet report, sending...
14:03:57.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:57.960808  9679 ts_manager.cc:194] Registered new tserver with Master: e42fbded00044b188d453144d5c6f646 (127.9.87.130:39799)
14:03:58.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.062053 10118 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:03:58.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.062316 10118 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:03:58.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.062374 10118 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.066095 10118 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:03:58.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.066228 10118 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.9.87.131
14:03:58.070 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.070420 10118 tablet_server_runner.cc:78] Tablet server non-default flags:
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.9.87.148:33277
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/wal
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.9.87.131:0
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data/info.pb
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.9.87.131
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.9.87.190:46813,127.9.87.189:46159,127.9.87.188:36479
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --heap_profile_path=/tmp/kudu.10118
14:03:58.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.9.87.131
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/logs
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type DEBUG
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:14 UTC on bdcb31816ec0
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11956
14:03:58.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.072104 10118 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:03:58.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.073408 10118 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:03:58.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.080034 10123 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
14:03:58.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.080022 10124 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
14:03:58.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:03:58.080049 10126 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
14:03:58.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.080298 10118 server_base.cc:1061] running on GCE node
14:03:58.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.080973 10118 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:03:58.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.081524 10118 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:03:58.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.082695 10118 hybrid_clock.cc:648] HybridClock initialized: now 1779113038082668 us; error 39 us; skew 500 ppm
14:03:58.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.084543 10118 webserver.cc:533] Webserver started at http://127.9.87.131:44719/ using document root <none> and password file <none>
14:03:58.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.085147 10118 fs_manager.cc:362] Metadata directory not provided
14:03:58.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.085209 10118 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:03:58.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.085453 10118 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:03:58.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.087046 10118 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data/instance:
14:03:58.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "03bb576ce8464b4bb2012126605ddedc"
14:03:58.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:58 on dist-test-slave-btpw"
14:03:58.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.087603 10118 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/wal/instance:
14:03:58.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "03bb576ce8464b4bb2012126605ddedc"
14:03:58.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:58 on dist-test-slave-btpw"
14:03:58.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.091012 10118 fs_manager.cc:696] Time spent creating directory manager: real 0.003s	user 0.002s	sys 0.001s
14:03:58.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.093303 10132 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:03:58.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.094400 10118 fs_manager.cc:730] Time spent opening block manager: real 0.002s	user 0.002s	sys 0.000s
14:03:58.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.094518 10118 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data,/tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/wal
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "03bb576ce8464b4bb2012126605ddedc"
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:03:58 on dist-test-slave-btpw"
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.094621 10118 fs_report.cc:389] FS layout report
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/wal
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/wal
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data/data
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:03:58.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:03:58.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.106623 10118 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:03:58.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.107379 10118 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:03:58.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.107589 10118 kserver.cc:163] Server-wide thread pool size limit: 3276
14:03:58.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.108202 10118 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:03:58.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.109257 10118 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:03:58.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.109306 10118 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:03:58.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.109365 10118 ts_tablet_manager.cc:616] Registered 0 tablets
14:03:58.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.109386 10118 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:03:58.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.118175 10118 rpc_server.cc:307] RPC server started. Bound to: 127.9.87.131:35989
14:03:58.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.118214 10246 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.9.87.131:35989 every 8 connection(s)
14:03:58.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.119257 10118 server_base.cc:1193] Dumped server information to /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/data/info.pb
14:03:58.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.123383  9566 external_mini_cluster.cc:1428] Started /tmp/dist-test-task1Tz940/build/debug/bin/kudu as pid 10118
14:03:58.124 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.123497  9566 external_mini_cluster.cc:1442] Reading /tmp/dist-test-task1Tz940/test-tmp/mini-kudu-cluster14239695524457955321/ts-2/wal/instance
14:03:58.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.129429 10247 heartbeater.cc:344] Connected to a master server at 127.9.87.188:36479
14:03:58.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.129528 10248 heartbeater.cc:344] Connected to a master server at 127.9.87.190:46813
14:03:58.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.129704 10247 heartbeater.cc:461] Registering TS with master...
14:03:58.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.129704 10248 heartbeater.cc:461] Registering TS with master...
14:03:58.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.130343 10248 heartbeater.cc:507] Master 127.9.87.190:46813 requested a full tablet report, sending...
14:03:58.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.130368 10249 heartbeater.cc:344] Connected to a master server at 127.9.87.189:46159
14:03:58.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.130719 10249 heartbeater.cc:461] Registering TS with master...
14:03:58.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.130923 10249 heartbeater.cc:507] Master 127.9.87.189:46159 requested a full tablet report, sending...
14:03:58.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.130342 10247 heartbeater.cc:507] Master 127.9.87.188:36479 requested a full tablet report, sending...
14:03:58.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.131695  9679 ts_manager.cc:194] Registered new tserver with Master: 03bb576ce8464b4bb2012126605ddedc (127.9.87.131:35989)
14:03:58.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.132119  9603 ts_manager.cc:194] Registered new tserver with Master: 03bb576ce8464b4bb2012126605ddedc (127.9.87.131:35989)
14:03:58.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.133010  9752 ts_manager.cc:194] Registered new tserver with Master: 03bb576ce8464b4bb2012126605ddedc (127.9.87.131:35989)
14:03:58.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.133649  9752 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.9.87.131:51915
14:03:58.145 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.145339  9566 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
14:03:58.146 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:03:58.147 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

14:03:58.157 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.9.87.190"
      port: 46813
    }
    bound_http_address {
      host: "127.9.87.190"
      port: 40115
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.9.87.189"
      port: 46159
    }
    bound_http_address {
      host: "127.9.87.189"
      port: 39939
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.9.87.188"
      port: 36479
    }
    bound_http_address {
      host: "127.9.87.188"
      port: 45645
    }
  }
}

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

14:03:58.163 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.9.87.129"
      port: 35987
    }
    bound_http_address {
      host: "127.9.87.129"
      port: 41091
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.9.87.130"
      port: 39799
    }
    bound_http_address {
      host: "127.9.87.130"
      port: 43479
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.9.87.131"
      port: 35989
    }
    bound_http_address {
      host: "127.9.87.131"
      port: 44719
    }
  }
}

14:03:58.163 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
14:03:58.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.341526  9807 catalog_manager.cc:1294] Loaded cluster ID: ed6e5ad0991742639a253668870bd6e9
14:03:58.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.341619  9807 catalog_manager.cc:1587] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: loading cluster ID for follower catalog manager: success
14:03:58.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.344177  9807 catalog_manager.cc:1609] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: acquiring CA information for follower catalog manager: success
14:03:58.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.345670  9807 catalog_manager.cc:1637] T 00000000000000000000000000000000 P 863c4805bc5748158d72d29381a3d719: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:03:58.353 [DEBUG - main] (CompositeStrategy.java:59) Unable to load modern Subject methods, relying only on legacy methods
java.lang.NoSuchMethodException: javax.security.auth.Subject.current()
	at java.base/java.lang.Class.getDeclaredMethod(Class.java:2677)
	at org.apache.kudu.client.internals.ModernStrategy.<init>(ModernStrategy.java:51)
	at org.apache.kudu.client.internals.CompositeStrategy.<init>(CompositeStrategy.java:52)
	at org.apache.kudu.client.internals.CompositeStrategy.<clinit>(CompositeStrategy.java:41)
	at org.apache.kudu.client.internals.SecurityManagerCompatibility.get(SecurityManagerCompatibility.java:43)
	at org.apache.kudu.client.SecurityContext.setupSubject(SecurityContext.java:155)
	at org.apache.kudu.client.SecurityContext.<init>(SecurityContext.java:138)
	at org.apache.kudu.client.AsyncKuduClient.<init>(AsyncKuduClient.java:431)
	at org.apache.kudu.client.AsyncKuduClient$AsyncKuduClientBuilder.build(AsyncKuduClient.java:3109)
	at org.apache.kudu.test.KuduTestHarness.before(KuduTestHarness.java:161)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:50)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.doOneAttemptAndReport(RetryRule.java:184)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:244)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.runner.JUnitCore.runMain(JUnitCore.java:77)
	at org.junit.runner.JUnitCore.main(JUnitCore.java:36)
14:03:58.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.405843  9835 catalog_manager.cc:1294] Loaded cluster ID: ed6e5ad0991742639a253668870bd6e9
14:03:58.406 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.405948  9835 catalog_manager.cc:1587] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: loading cluster ID for follower catalog manager: success
14:03:58.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.408452  9835 catalog_manager.cc:1609] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: acquiring CA information for follower catalog manager: success
14:03:58.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.410189  9835 catalog_manager.cc:1637] T 00000000000000000000000000000000 P c94563bc0fbc4e0ca8a733cd965345a5: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:03:58.413 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
14:03:58.556 [DEBUG - Time-limited test] (Connection.java:748) [peer master-127.9.87.188:36479(127.9.87.188:36479)] connecting to peer
14:03:58.608 [DEBUG - Time-limited test] (Connection.java:748) [peer master-127.9.87.189:46159(127.9.87.189:46159)] connecting to peer
14:03:58.610 [DEBUG - Time-limited test] (Connection.java:748) [peer master-127.9.87.190:46813(127.9.87.190:46813)] connecting to peer
14:03:58.626 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.9.87.190:46813(127.9.87.190:46813)] Successfully connected to peer
14:03:58.626 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.9.87.189:46159(127.9.87.189:46159)] Successfully connected to peer
14:03:58.626 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.9.87.188:36479(127.9.87.188:36479)] Successfully connected to peer
14:03:58.720 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.9.87.189
14:03:58.721 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.9.87.188
14:03:58.721 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.9.87.190
14:03:58.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.780555  9976 heartbeater.cc:499] Master 127.9.87.188:36479 was elected leader, sending a full tablet report...
14:03:58.942 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0xeaeef73b, L:/127.0.0.1:50494 - R:/127.9.87.190:46813] using SASL/PLAIN
14:03:58.942 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x6ad67068, L:/127.0.0.1:39952 - R:/127.9.87.188:36479] using SASL/PLAIN
14:03:58.942 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x303fdf5f, L:/127.0.0.1:39450 - R:/127.9.87.189:46159] using SASL/PLAIN
14:03:58.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:58.960732 10111 heartbeater.cc:499] Master 127.9.87.188:36479 was elected leader, sending a full tablet report...
14:03:58.979 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
14:03:58.980 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=ListTables, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=462), Traces: [0ms] refreshing cache from master, [22ms] Sub RPC ConnectToMaster: sending RPC to server master-127.9.87.188:36479, [86ms] Sub RPC ConnectToMaster: sending RPC to server master-127.9.87.189:46159, [88ms] Sub RPC ConnectToMaster: sending RPC to server master-127.9.87.190:46813, [435ms] Sub RPC ConnectToMaster: received response from server master-127.9.87.190:46813: OK, [435ms] Sub RPC ConnectToMaster: received response from server master-127.9.87.189:46159: OK, [435ms] Sub RPC ConnectToMaster: received response from server master-127.9.87.188:36479: OK, deferred=null) after lookup
14:03:58.992 [DEBUG - Time-limited test] (Connection.java:575) [peer master-127.9.87.188:36479(127.9.87.188:36479)] disconnecting while in state READY
14:03:58.998 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.9.87.188:36479(127.9.87.188:36479)] handling channelInactive
14:03:58.998 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.9.87.188:36479(127.9.87.188:36479)] cleaning up while in state READY due to: connection closed
14:03:59.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:03:59.135372 10247 heartbeater.cc:499] Master 127.9.87.188:36479 was elected leader, sending a full tablet report...
14:03:59.749 [DEBUG - Time-limited test] (Connection.java:748) [peer fake-uuid(127.9.87.188:36479)] connecting to peer
14:03:59.751 [DEBUG - kudu-nio-3] (Connection.java:757) [peer fake-uuid(127.9.87.188:36479)] Successfully connected to peer
14:03:59.813 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x9b6e9071, L:/127.0.0.1:39968 - R:/127.9.87.188:36479] using TOKEN/null
14:03:59.820 [DEBUG - Time-limited test] (Connection.java:575) [peer master-127.9.87.190:46813(127.9.87.190:46813)] disconnecting while in state READY
14:03:59.821 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.9.87.190:46813(127.9.87.190:46813)] handling channelInactive
14:03:59.821 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.9.87.190:46813(127.9.87.190:46813)] cleaning up while in state READY due to: connection closed
14:04:00.071 [DEBUG - Time-limited test] (Connection.java:575) [peer fake-uuid(127.9.87.188:36479)] disconnecting while in state READY
14:04:00.074 [DEBUG - kudu-nio-3] (Connection.java:239) [peer fake-uuid(127.9.87.188:36479)] handling channelInactive
14:04:00.074 [DEBUG - kudu-nio-3] (Connection.java:709) [peer fake-uuid(127.9.87.188:36479)] cleaning up while in state READY due to: connection closed
14:04:00.324 [DEBUG - Time-limited test] (Connection.java:575) [peer master-127.9.87.189:46159(127.9.87.189:46159)] disconnecting while in state READY
14:04:00.326 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.9.87.189:46159(127.9.87.189:46159)] handling channelInactive
14:04:00.326 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.9.87.189:46159(127.9.87.189:46159)] cleaning up while in state READY due to: connection closed
14:04:00.577 [DEBUG - main] (Connection.java:575) [peer master-127.9.87.190:46813(127.9.87.190:46813)] disconnecting while in state TERMINATED
14:04:00.578 [DEBUG - main] (Connection.java:575) [peer fake-uuid(127.9.87.188:36479)] disconnecting while in state TERMINATED
14:04:00.578 [DEBUG - main] (Connection.java:575) [peer master-127.9.87.189:46159(127.9.87.189:46159)] disconnecting while in state TERMINATED
14:04:00.580 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
14:04:00.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:00.583196  9566 external_mini_cluster.cc:1658] Killing /tmp/dist-test-task1Tz940/build/debug/bin/kudu with pid 9848
14:04:00.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:00.588636  9566 external_mini_cluster.cc:1658] Killing /tmp/dist-test-task1Tz940/build/debug/bin/kudu with pid 9983
14:04:00.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:00.593921  9566 external_mini_cluster.cc:1658] Killing /tmp/dist-test-task1Tz940/build/debug/bin/kudu with pid 10118
14:04:00.599 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:00.599220  9566 external_mini_cluster.cc:1658] Killing /tmp/dist-test-task1Tz940/build/debug/bin/kudu with pid 9577
14:04:00.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:00.605726  9566 external_mini_cluster.cc:1658] Killing /tmp/dist-test-task1Tz940/build/debug/bin/kudu with pid 9646
14:04:00.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:00.606930  9736 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.9.87.190:46813: connect: Connection refused (error 111)
14:04:00.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:00.608099  9736 consensus_peers.cc:597] T 00000000000000000000000000000000 P 10e311f249d84b0a8234241898c9c3df -> Peer 863c4805bc5748158d72d29381a3d719 (127.9.87.190:46813): Couldn't send request to peer 863c4805bc5748158d72d29381a3d719. Status: Network error: Client connection negotiation failed: client connection to 127.9.87.190:46813: connect: Connection refused (error 111). This is attempt 1: this message will repeat every 5th retry.
14:04:00.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:00.611776  9566 external_mini_cluster.cc:1658] Killing /tmp/dist-test-task1Tz940/build/debug/bin/kudu with pid 9718
14:04:00.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:04:00Z chronyd exiting

Time: 4.999

OK (1 test)

