JUnit version 4.13.2
.14:04:12.470 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1779113052461
14:04:12.494 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
14:04:12.551 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
14:04:12.561 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskqJwMAx/test-tmp
14:04:12.569 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
14:04:12.572 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskqJwMAx/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
14:04:12.856 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

14:04:13.020 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:04:13.021 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

14:04:13.026 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.025307 19063 mini_oidc.cc:150] Starting JWKS server
14:04:13.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.029363 19063 webserver.cc:533] Webserver started at http://127.0.0.1:37403/ using document root <none> and password file <none>
14:04:13.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.029770 19063 mini_oidc.cc:186] Starting OIDC Discovery server
14:04:13.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.031682 19063 webserver.cc:533] Webserver started at http://127.0.0.1:41293/ using document root <none> and password file <none>
14:04:13.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:04:13Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
14:04:13.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:04:13Z Disabled control of system clock
14:04:13.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.092224 19063 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:13.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:13.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/wal
14:04:13.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data
14:04:13.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:04:13.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:04:13.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:04:13.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:13.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:13.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:13.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/logs
14:04:13.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data/info.pb
14:04:13.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:13.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:04:13.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:04:13.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:04:13.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:04:13.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:13.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:13.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:13.098 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:04:13.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:04:13.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:04:13.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:04:13.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.254:40441
14:04:13.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.254
14:04:13.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:13.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:13.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:13.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:13.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:13.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:04:13.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:13.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:04:13.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37403/jwks.json with env {}
14:04:13.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.397935 19075 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:04:13.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.398557 19075 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:04:13.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.399163 19075 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:04:13.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.431919 19075 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:04:13.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.432212 19075 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:04:13.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.432402 19075 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:04:13.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.432585 19075 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:04:13.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.432770 19075 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:04:13.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.432911 19075 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37403/jwks.json
14:04:13.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.470364 19075 master_runner.cc:387] Master server non-default flags:
14:04:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:13.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data
14:04:13.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/wal
14:04:13.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:04:13.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:13.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:13.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:13.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:04:13.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.254:40441
14:04:13.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:04:13.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:04:13.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:04:13.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37403/jwks.json
14:04:13.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:13.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:13.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data/info.pb
14:04:13.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.254
14:04:13.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:13.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:04:13.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:13.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:04:13.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:04:13.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:13.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/logs
14:04:13.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:13.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:04:13.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:04:13.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:04:13.477 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:04:13.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:04:13.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:04:13.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:22 UTC on e7f111948823
14:04:13.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11959
14:04:13.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:04:13.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.471805 19075 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:04:13.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:13.473663 19075 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:04:13.486 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.485460 19081 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:04:14.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:13.486647 19082 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:04:14.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:14.703954 19084 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:04:14.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:14.709060 19075 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.223s	user 0.382s	sys 0.838s
14:04:14.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:14.709299 19083 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1221 milliseconds
14:04:14.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:14.709389 19075 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.224s	user 0.382s	sys 0.838s
14:04:14.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.709700 19075 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:04:14.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.711227 19075 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:04:14.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.718864 19075 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:04:14.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.720250 19075 hybrid_clock.cc:648] HybridClock initialized: now 1779113054720200 us; error 70 us; skew 500 ppm
14:04:14.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.721233 19075 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:04:14.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.729665 19075 webserver.cc:533] Webserver started at http://127.18.157.254:44269/ using document root <none> and password file <none>
14:04:14.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.730919 19075 fs_manager.cc:362] Metadata directory not provided
14:04:14.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.731179 19075 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:04:14.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.731799 19075 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:04:14.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.736469 19075 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data/instance:
14:04:14.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eba92e8df6c14358a64258a009bcd9f3"
14:04:14.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:14 on dist-test-slave-ktkl"
14:04:14.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.737931 19075 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/wal/instance:
14:04:14.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eba92e8df6c14358a64258a009bcd9f3"
14:04:14.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:14 on dist-test-slave-ktkl"
14:04:14.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.748149 19075 fs_manager.cc:696] Time spent creating directory manager: real 0.009s	user 0.008s	sys 0.000s
14:04:14.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.754791 19091 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:04:14.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.756033 19075 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.001s
14:04:14.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.756404 19075 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data,/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/wal
14:04:14.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eba92e8df6c14358a64258a009bcd9f3"
14:04:14.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:14 on dist-test-slave-ktkl"
14:04:14.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.756861 19075 fs_report.cc:389] FS layout report
14:04:14.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:04:14.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/wal
14:04:14.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/wal
14:04:14.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data/data
14:04:14.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:04:14.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:04:14.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:04:14.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:04:14.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:04:14.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:04:14.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:04:14.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:04:14.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:04:14.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:04:14.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:04:14.827 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.826565 19075 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:04:14.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.828332 19075 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:04:14.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.828859 19075 kserver.cc:163] Server-wide thread pool size limit: 3276
14:04:14.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.900462 19075 rpc_server.cc:307] RPC server started. Bound to: 127.18.157.254:40441
14:04:14.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.900548 19142 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.157.254:40441 every 8 connection(s)
14:04:14.903 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.903499 19075 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/data/info.pb
14:04:14.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.906981 19063 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu as pid 19075
14:04:14.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.907347 19063 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-0/wal/instance
14:04:14.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.910162 19143 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:04:14.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.911000 19063 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:14.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:14.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/wal
14:04:14.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data
14:04:14.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:04:14.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:04:14.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:04:14.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:14.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:14.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:14.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/logs
14:04:14.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data/info.pb
14:04:14.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:14.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:04:14.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:04:14.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:04:14.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:04:14.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.253:44395
14:04:14.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.253
14:04:14.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:14.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:14.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:14.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:14.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:14.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:04:14.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:14.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:04:14.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37403/jwks.json with env {}
14:04:14.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.924806 19143 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } has no permanent_uuid. Determining permanent_uuid...
14:04:14.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:14.954092 19143 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } has no permanent_uuid. Determining permanent_uuid...
14:04:14.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:14.956858 19094 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:14.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:14.960958 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.253:44395: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:15.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.010519 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } attempt: 1
14:04:15.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.014834 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.253:44395: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:15.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.083316 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } attempt: 2
14:04:15.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.088307 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.253:44395: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:15.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.179718 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } attempt: 3
14:04:15.184 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.183763 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.253:44395: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:15.230 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.229950 19145 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:04:15.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.230608 19145 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:04:15.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.231339 19145 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:04:15.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.273947 19145 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:04:15.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.274214 19145 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:04:15.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.274510 19145 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:04:15.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.274709 19145 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:04:15.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.274888 19145 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:04:15.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.275027 19145 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37403/jwks.json
14:04:15.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.310988 19145 master_runner.cc:387] Master server non-default flags:
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/wal
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:04:15.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.253:44395
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:04:15.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37403/jwks.json
14:04:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data/info.pb
14:04:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.253
14:04:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:15.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:04:15.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:15.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:04:15.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:04:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/logs
14:04:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:04:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:04:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:04:15.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:04:15.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:04:15.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:04:15.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:22 UTC on e7f111948823
14:04:15.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11959
14:04:15.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:04:15.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.312440 19145 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:04:15.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.314096 19145 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:04:15.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.327160 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } attempt: 4
14:04:15.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.328014 19155 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:04:15.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.333637 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.253:44395: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:15.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.633098 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } attempt: 5
14:04:15.638 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.637639 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.253:44395: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:16.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.185325 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } attempt: 6
14:04:16.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:16.192729 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.253:44395: Network error: Client connection negotiation failed: client connection to 127.18.157.253:44395: connect: Connection refused (error 111)
14:04:16.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.332078 19154 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:04:16.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:15.334569 19145 server_base.cc:1061] running on GCE node
14:04:16.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:15.334611 19157 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:04:16.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.524713 19145 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:04:16.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.528134 19145 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:04:16.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.529623 19145 hybrid_clock.cc:648] HybridClock initialized: now 1779113056529547 us; error 86 us; skew 500 ppm
14:04:16.531 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.530799 19145 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:04:16.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.537835 19145 webserver.cc:533] Webserver started at http://127.18.157.253:44115/ using document root <none> and password file <none>
14:04:16.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.538820 19145 fs_manager.cc:362] Metadata directory not provided
14:04:16.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.539014 19145 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:04:16.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.539441 19145 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:04:16.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.546119 19145 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data/instance:
14:04:16.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "284e4a5282684e3a89eaf702df4558d2"
14:04:16.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:16 on dist-test-slave-ktkl"
14:04:16.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.547683 19145 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/wal/instance:
14:04:16.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "284e4a5282684e3a89eaf702df4558d2"
14:04:16.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:16 on dist-test-slave-ktkl"
14:04:16.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.555318 19145 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.004s	sys 0.004s
14:04:16.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.561101 19166 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:04:16.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.562500 19145 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.000s
14:04:16.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.562911 19145 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data,/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/wal
14:04:16.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "284e4a5282684e3a89eaf702df4558d2"
14:04:16.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:16 on dist-test-slave-ktkl"
14:04:16.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.563366 19145 fs_report.cc:389] FS layout report
14:04:16.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:04:16.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/wal
14:04:16.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/wal
14:04:16.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data/data
14:04:16.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:04:16.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:04:16.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:04:16.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:04:16.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:04:16.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:04:16.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:04:16.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:04:16.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:04:16.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:04:16.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:04:16.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.617084 19145 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:04:16.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.618709 19145 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:04:16.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.619128 19145 kserver.cc:163] Server-wide thread pool size limit: 3276
14:04:16.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.694197 19145 rpc_server.cc:307] RPC server started. Bound to: 127.18.157.253:44395
14:04:16.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.694311 19217 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.157.253:44395 every 8 connection(s)
14:04:16.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.697420 19145 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/data/info.pb
14:04:16.706 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.706017 19218 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:04:16.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.706389 19063 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu as pid 19145
14:04:16.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.706869 19063 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-1/wal/instance
14:04:16.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.709278 19063 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:16.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:16.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/wal
14:04:16.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/logs
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data/info.pb
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:04:16.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:04:16.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.252:39873
14:04:16.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.252
14:04:16.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:16.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:16.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:16.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:16.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:16.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:04:16.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:16.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:04:16.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37403/jwks.json with env {}
14:04:16.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.723752 19218 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } has no permanent_uuid. Determining permanent_uuid...
14:04:16.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.750699 19218 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } has no permanent_uuid. Determining permanent_uuid...
14:04:16.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.765494 19218 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } has no permanent_uuid. Determining permanent_uuid...
14:04:16.768 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:16.768082 19168 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:16.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:16.772563 19218 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:16.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.822160 19218 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 1
14:04:16.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:16.826473 19218 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:16.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.894886 19218 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 2
14:04:16.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:16.898974 19218 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:16.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:16.990429 19218 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 3
14:04:16.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:16.995512 19218 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.029507 19220 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:04:17.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.030035 19220 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:04:17.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.030540 19220 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:04:17.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.063241 19220 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:04:17.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.063521 19220 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:04:17.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.063723 19220 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:04:17.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.063910 19220 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:04:17.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.064103 19220 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:04:17.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.064308 19220 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:37403/jwks.json
14:04:17.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.101963 19220 master_runner.cc:387] Master server non-default flags:
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/wal
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:17.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.252:39873
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:37403/jwks.json
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data/info.pb
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.252
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:17.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/logs
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:04:17.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:22 UTC on e7f111948823
14:04:17.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11959
14:04:17.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:04:17.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.103644 19220 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:04:17.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.105826 19220 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:04:17.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.116887 19230 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:04:17.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.138919 19218 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 4
14:04:17.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.142762 19218 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.253319 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } attempt: 7
14:04:17.266 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.265997 19143 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } has no permanent_uuid. Determining permanent_uuid...
14:04:17.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.271394 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.330034 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 1
14:04:17.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.335737 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.417158 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 2
14:04:17.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.422171 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.442416 19218 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 5
14:04:17.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.448474 19218 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.507637 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 3
14:04:17.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.512372 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.652814 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 4
14:04:17.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.657553 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.941146 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 5
14:04:17.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.945618 19143 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:17.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:17.996112 19218 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 6
14:04:18.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.003772 19218 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.18.157.252:39873: Network error: Client connection negotiation failed: client connection to 127.18.157.252:39873: connect: Connection refused (error 111)
14:04:18.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.122293 19233 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:04:18.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:17.118891 19231 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:04:18.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.319808 19232 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
14:04:18.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.319916 19220 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:04:18.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.323853 19220 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:04:18.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.326687 19220 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:04:18.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.328080 19220 hybrid_clock.cc:648] HybridClock initialized: now 1779113058328042 us; error 42 us; skew 500 ppm
14:04:18.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.329025 19220 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:04:18.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.335165 19220 webserver.cc:533] Webserver started at http://127.18.157.252:39687/ using document root <none> and password file <none>
14:04:18.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.336158 19220 fs_manager.cc:362] Metadata directory not provided
14:04:18.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.336421 19220 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:04:18.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.337000 19220 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:04:18.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.341632 19220 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data/instance:
14:04:18.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4805de7a9c60441282e3a25c96dada9e"
14:04:18.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:18 on dist-test-slave-ktkl"
14:04:18.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.342934 19220 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/wal/instance:
14:04:18.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4805de7a9c60441282e3a25c96dada9e"
14:04:18.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:18 on dist-test-slave-ktkl"
14:04:18.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.350232 19220 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.001s
14:04:18.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.356580 19244 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:04:18.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.357712 19220 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.001s
14:04:18.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.358060 19220 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data,/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/wal
14:04:18.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "4805de7a9c60441282e3a25c96dada9e"
14:04:18.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:18 on dist-test-slave-ktkl"
14:04:18.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.358505 19220 fs_report.cc:389] FS layout report
14:04:18.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:04:18.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/wal
14:04:18.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/wal
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data/data
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:04:18.360 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:04:18.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:04:18.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:04:18.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:04:18.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.417346 19220 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:04:18.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.419029 19220 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:04:18.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.419597 19220 kserver.cc:163] Server-wide thread pool size limit: 3276
14:04:18.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.497519 19220 rpc_server.cc:307] RPC server started. Bound to: 127.18.157.252:39873
14:04:18.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.497605 19295 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.157.252:39873 every 8 connection(s)
14:04:18.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.498208 19143 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 6
14:04:18.502 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.501830 19220 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/data/info.pb
14:04:18.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.503139 19063 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu as pid 19220
14:04:18.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.503595 19063 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/master-2/wal/instance
14:04:18.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.510967 19298 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:04:18.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.533165 19298 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } has no permanent_uuid. Determining permanent_uuid...
14:04:18.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.538455 19143 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: Bootstrap starting.
14:04:18.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.548774 19143 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: Neither blocks nor log segments found. Creating new log.
14:04:18.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.551960 19143 log.cc:826] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: Log is configured to *not* fsync() on all Append() calls
14:04:18.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.561035 19143 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: No bootstrap required, opened a new log
14:04:18.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.568547 19298 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } has no permanent_uuid. Determining permanent_uuid...
14:04:18.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.581388 19298 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } has no permanent_uuid. Determining permanent_uuid...
14:04:18.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.586432 19143 raft_consensus.cc:359] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:18.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.587780 19143 raft_consensus.cc:385] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:04:18.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.588363 19143 raft_consensus.cc:740] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: eba92e8df6c14358a64258a009bcd9f3, State: Initialized, Role: FOLLOWER
14:04:18.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.589569 19143 consensus_queue.cc:260] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [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: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:18.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.592767 19306 sys_catalog.cc:455] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [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: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:18.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.594673 19143 sys_catalog.cc:565] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [sys.catalog]: configured and running, proceeding with master startup.
14:04:18.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.596432 19306 sys_catalog.cc:458] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [sys.catalog]: This master's current role is: FOLLOWER
14:04:18.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.602936 19298 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e: Bootstrap starting.
14:04:18.612 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.612304 19298 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e: Neither blocks nor log segments found. Creating new log.
14:04:18.614 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.614153 19298 log.cc:826] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e: Log is configured to *not* fsync() on all Append() calls
14:04:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.620959 19298 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e: No bootstrap required, opened a new log
14:04:18.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.643015 19318 catalog_manager.cc:1593] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:04:18.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.643899 19318 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:04:18.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.646519 19298 raft_consensus.cc:359] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:18.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.647820 19298 raft_consensus.cc:385] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:04:18.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.648468 19298 raft_consensus.cc:740] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 4805de7a9c60441282e3a25c96dada9e, State: Initialized, Role: FOLLOWER
14:04:18.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.649946 19298 consensus_queue.cc:260] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [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: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:18.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.654451 19320 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [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: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:18.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.655975 19320 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: This master's current role is: FOLLOWER
14:04:18.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.656453 19298 sys_catalog.cc:565] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: configured and running, proceeding with master startup.
14:04:18.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.674952 19250 tablet.cc:2404] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:04:18.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.638149 19075 master_runner.cc:187] Error getting master registration for 127.18.157.253:44395: OK, instance_id { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" instance_seqno: 1779113056674015 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:04:18.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.679412 19075 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:04:18.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.694931 19320 raft_consensus.cc:493] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:04:18.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.697475 19320 raft_consensus.cc:515] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:18.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.709379 19118 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4805de7a9c60441282e3a25c96dada9e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "eba92e8df6c14358a64258a009bcd9f3" is_pre_election: true
14:04:18.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.710260 19118 raft_consensus.cc:2468] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 4805de7a9c60441282e3a25c96dada9e in term 0.
14:04:18.712 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.711975 19245 leader_election.cc:304] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [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: 4805de7a9c60441282e3a25c96dada9e, eba92e8df6c14358a64258a009bcd9f3; no voters: 
14:04:18.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.713259 19320 leader_election.cc:290] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers eba92e8df6c14358a64258a009bcd9f3 (127.18.157.254:40441), 284e4a5282684e3a89eaf702df4558d2 (127.18.157.253:44395)
14:04:18.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.713761 19320 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Leader pre-election won for term 1
14:04:18.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.714529 19320 raft_consensus.cc:493] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:04:18.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.714834 19320 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 0 FOLLOWER]: Advancing to term 1
14:04:18.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.739892 19320 raft_consensus.cc:515] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:18.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.749605 19320 leader_election.cc:290] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [CANDIDATE]: Term 1 election: Requested vote from peers eba92e8df6c14358a64258a009bcd9f3 (127.18.157.254:40441), 284e4a5282684e3a89eaf702df4558d2 (127.18.157.253:44395)
14:04:18.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.751137 19118 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4805de7a9c60441282e3a25c96dada9e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "eba92e8df6c14358a64258a009bcd9f3"
14:04:18.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.751721 19118 raft_consensus.cc:3060] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [term 0 FOLLOWER]: Advancing to term 1
14:04:18.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.759905 19118 raft_consensus.cc:2468] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 4805de7a9c60441282e3a25c96dada9e in term 1.
14:04:18.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.759636 19331 catalog_manager.cc:1593] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:04:18.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.711629 19220 master_runner.cc:187] Error getting master registration for 127.18.157.253:44395: OK, instance_id { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" instance_seqno: 1779113056674015 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:04:18.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.762394 19220 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:04:18.763 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.761135 19331 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:04:18.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.779991 19245 leader_election.cc:304] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [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: 4805de7a9c60441282e3a25c96dada9e, eba92e8df6c14358a64258a009bcd9f3; no voters: 
14:04:18.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.783582 19320 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 1 FOLLOWER]: Leader election won for term 1
14:04:18.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.784089 19320 raft_consensus.cc:697] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [term 1 LEADER]: Becoming Leader. State: Replica: 4805de7a9c60441282e3a25c96dada9e, State: Running, Role: LEADER
14:04:18.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.784965 19320 consensus_queue.cc:237] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [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: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:18.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.793582 19332 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4805de7a9c60441282e3a25c96dada9e. Latest consensus state: current_term: 1 leader_uuid: "4805de7a9c60441282e3a25c96dada9e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:18.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.794410 19332 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: This master's current role is: LEADER
14:04:18.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.801337 19335 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
14:04:18.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.813941 19335 catalog_manager.cc:1519] Initializing Kudu cluster ID...
14:04:18.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:18.839318 19097 tablet.cc:2404] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:04:18.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.843050 19118 raft_consensus.cc:1275] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [term 1 FOLLOWER]: Refusing update from remote peer 4805de7a9c60441282e3a25c96dada9e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:04:18.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.844851 19320 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [LEADER]: Connected to new peer: Peer: permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:04:18.887 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.886288 19336 mvcc.cc:204] Tried to move back new op lower bound from 7287247088984989696 to 7287247088797454336. Current Snapshot: MvccSnapshot[applied={T|T < 7287247088984989696}]
14:04:18.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.885793 19306 sys_catalog.cc:455] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4805de7a9c60441282e3a25c96dada9e. Latest consensus state: current_term: 1 leader_uuid: "4805de7a9c60441282e3a25c96dada9e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:18.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.895149 19306 sys_catalog.cc:458] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [sys.catalog]: This master's current role is: FOLLOWER
14:04:18.902 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.901473 19337 mvcc.cc:204] Tried to move back new op lower bound from 7287247088984989696 to 7287247088797454336. Current Snapshot: MvccSnapshot[applied={T|T < 7287247088984989696}]
14:04:18.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.905823 19332 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "4805de7a9c60441282e3a25c96dada9e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:18.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.906538 19332 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: This master's current role is: LEADER
14:04:18.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.907747 19335 catalog_manager.cc:1382] Generated new cluster ID: 01122c92c04746c3bbafc8efa93ff5a8
14:04:18.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.908012 19335 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
14:04:18.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.918761 19306 sys_catalog.cc:455] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "4805de7a9c60441282e3a25c96dada9e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:18.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.919947 19306 sys_catalog.cc:458] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3 [sys.catalog]: This master's current role is: FOLLOWER
14:04:18.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.957196 19335 catalog_manager.cc:1405] Generated new certificate authority record
14:04:18.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.960276 19335 catalog_manager.cc:1539] Loading token signing keys...
14:04:18.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.990718 19335 catalog_manager.cc:6069] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e: Generated new TSK 0
14:04:18.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:18.994678 19335 catalog_manager.cc:1549] Initializing in-progress tserver states...
14:04:19.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.042100 19063 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:19.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/wal
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/logs
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data/info.pb
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:04:19.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.193:0
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.18.157.193
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.193
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:19.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:19.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:04:19.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.064307 19218 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } attempt: 7
14:04:19.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.100454 19218 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2: Bootstrap starting.
14:04:19.121 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.120787 19218 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2: Neither blocks nor log segments found. Creating new log.
14:04:19.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.127848 19218 log.cc:826] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2: Log is configured to *not* fsync() on all Append() calls
14:04:19.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.139994 19218 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2: No bootstrap required, opened a new log
14:04:19.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.213178 19218 raft_consensus.cc:359] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:19.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.215735 19218 raft_consensus.cc:385] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:04:19.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.216809 19218 raft_consensus.cc:740] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 284e4a5282684e3a89eaf702df4558d2, State: Initialized, Role: FOLLOWER
14:04:19.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.219736 19218 consensus_queue.cc:260] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [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: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } }
14:04:19.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.241319 19192 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4805de7a9c60441282e3a25c96dada9e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "284e4a5282684e3a89eaf702df4558d2"
14:04:19.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.242888 19193 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "4805de7a9c60441282e3a25c96dada9e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "284e4a5282684e3a89eaf702df4558d2" is_pre_election: true
14:04:19.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.246034 19192 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [term 0 FOLLOWER]: Advancing to term 1
14:04:19.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.253557 19192 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 4805de7a9c60441282e3a25c96dada9e in term 1.
14:04:19.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.255268 19218 sys_catalog.cc:565] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [sys.catalog]: configured and running, proceeding with master startup.
14:04:19.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.260730 19191 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [term 1 FOLLOWER]: Refusing update from remote peer 4805de7a9c60441282e3a25c96dada9e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:04:19.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.264533 19332 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [LEADER]: Connected to new peer: Peer: permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.001s
14:04:19.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.261121 19344 sys_catalog.cc:455] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [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: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:19.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.290517 19344 sys_catalog.cc:458] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [sys.catalog]: This master's current role is: FOLLOWER
14:04:19.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.351949 19348 mvcc.cc:204] Tried to move back new op lower bound from 7287247088984989696 to 7287247088797454336. Current Snapshot: MvccSnapshot[applied={T|T < 7287247088984989696}]
14:04:19.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.367561 19345 sys_catalog.cc:455] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "4805de7a9c60441282e3a25c96dada9e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:19.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.368328 19345 sys_catalog.cc:458] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [sys.catalog]: This master's current role is: FOLLOWER
14:04:19.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.366104 19344 sys_catalog.cc:455] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 4805de7a9c60441282e3a25c96dada9e. Latest consensus state: current_term: 1 leader_uuid: "4805de7a9c60441282e3a25c96dada9e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:19.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.369876 19344 sys_catalog.cc:458] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2 [sys.catalog]: This master's current role is: FOLLOWER
14:04:19.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.411260 19360 catalog_manager.cc:805] Waiting for catalog manager background task thread to start: Service unavailable: Catalog manager is not initialized. State: Starting
14:04:19.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:19.584692 19341 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:04:19.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:19.586513 19341 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:04:19.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:19.587687 19341 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:04:19.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:19.650199 19341 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:04:19.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.651198 19318 catalog_manager.cc:1294] Loaded cluster ID: 01122c92c04746c3bbafc8efa93ff5a8
14:04:19.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.651741 19318 catalog_manager.cc:1587] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: loading cluster ID for follower catalog manager: success
14:04:19.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:19.651965 19341 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.18.157.193
14:04:19.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.659526 19318 catalog_manager.cc:1609] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: acquiring CA information for follower catalog manager: success
14:04:19.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.665522 19318 catalog_manager.cc:1637] T 00000000000000000000000000000000 P eba92e8df6c14358a64258a009bcd9f3: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:04:19.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.719342 19341 tablet_server_runner.cc:78] Tablet server non-default flags:
14:04:19.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:19.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:19.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/wal
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.193:0
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data/info.pb
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.193
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:19.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.18.157.193
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/logs
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:22 UTC on e7f111948823
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11959
14:04:19.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:04:19.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.721793 19341 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:04:19.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.727334 19341 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:04:19.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:19.761971 19367 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:04:19.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.789803 19332 sys_catalog.cc:455] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "4805de7a9c60441282e3a25c96dada9e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eba92e8df6c14358a64258a009bcd9f3" member_type: VOTER last_known_addr { host: "127.18.157.254" port: 40441 } } peers { permanent_uuid: "284e4a5282684e3a89eaf702df4558d2" member_type: VOTER last_known_addr { host: "127.18.157.253" port: 44395 } } peers { permanent_uuid: "4805de7a9c60441282e3a25c96dada9e" member_type: VOTER last_known_addr { host: "127.18.157.252" port: 39873 } } }
14:04:19.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:19.798324 19332 sys_catalog.cc:458] T 00000000000000000000000000000000 P 4805de7a9c60441282e3a25c96dada9e [sys.catalog]: This master's current role is: LEADER
14:04:20.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:20.436774 19360 catalog_manager.cc:1294] Loaded cluster ID: 01122c92c04746c3bbafc8efa93ff5a8
14:04:20.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:20.437245 19360 catalog_manager.cc:1587] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2: loading cluster ID for follower catalog manager: success
14:04:20.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:20.483445 19360 catalog_manager.cc:1609] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2: acquiring CA information for follower catalog manager: success
14:04:20.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:20.524463 19360 catalog_manager.cc:1637] T 00000000000000000000000000000000 P 284e4a5282684e3a89eaf702df4558d2: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:04:21.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:21.150171 19365 debug-util.cc:398] Leaking SignalData structure 0x7b08000271c0 after lost signal to thread 19341
14:04:21.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:21.342911 19365 kernel_stack_watchdog.cc:198] Thread 19341 stuck at /home/jenkins-slave/workspace/build_and_test_flaky@2/src/kudu/util/thread.cc:642 for 389ms:
14:04:21.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
14:04:21.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
14:04:21.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
14:04:21.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
14:04:21.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:19.764662 19366 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:04:21.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:21.348762 19341 thread.cc:641] GCE (cloud detector) Time spent creating pthread: real 1.589s	user 0.491s	sys 0.898s
14:04:21.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:21.349385 19341 thread.cc:608] GCE (cloud detector) Time spent starting thread: real 1.590s	user 0.491s	sys 0.899s
14:04:21.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.353652 19341 server_base.cc:1061] running on GCE node
14:04:21.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:21.355273 19374 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:04:21.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.356889 19341 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:04:21.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.374939 19341 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:04:21.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.376973 19341 hybrid_clock.cc:648] HybridClock initialized: now 1779113061376935 us; error 52 us; skew 500 ppm
14:04:21.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.378096 19341 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:04:21.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.400547 19341 webserver.cc:533] Webserver started at http://127.18.157.193:38331/ using document root <none> and password file <none>
14:04:21.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.402202 19341 fs_manager.cc:362] Metadata directory not provided
14:04:21.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.403614 19341 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:04:21.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.404423 19341 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:04:21.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.411401 19341 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data/instance:
14:04:21.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "82665e2d98ce4566b45a9d183c9a0493"
14:04:21.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:21 on dist-test-slave-ktkl"
14:04:21.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.413259 19341 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/wal/instance:
14:04:21.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "82665e2d98ce4566b45a9d183c9a0493"
14:04:21.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:21 on dist-test-slave-ktkl"
14:04:21.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.429958 19341 fs_manager.cc:696] Time spent creating directory manager: real 0.016s	user 0.006s	sys 0.007s
14:04:21.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.443343 19379 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:04:21.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.445048 19341 fs_manager.cc:730] Time spent opening block manager: real 0.010s	user 0.004s	sys 0.004s
14:04:21.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.445708 19341 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data,/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/wal
14:04:21.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "82665e2d98ce4566b45a9d183c9a0493"
14:04:21.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:21 on dist-test-slave-ktkl"
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.446622 19341 fs_report.cc:389] FS layout report
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/wal
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/wal
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data/data
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:04:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:04:21.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:04:21.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:04:21.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:04:21.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:04:21.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.571552 19341 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:04:21.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.573997 19341 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:04:21.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.574932 19341 kserver.cc:163] Server-wide thread pool size limit: 3276
14:04:21.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.584463 19341 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:04:21.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.597645 19341 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:04:21.599 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.599074 19341 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.001s	user 0.000s	sys 0.001s
14:04:21.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.599767 19341 ts_tablet_manager.cc:616] Registered 0 tablets
14:04:21.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:21.601989 19341 ts_tablet_manager.cc:595] Time spent register tablets: real 0.002s	user 0.000s	sys 0.000s
14:04:22.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.365962 19341 rpc_server.cc:307] RPC server started. Bound to: 127.18.157.193:34963
14:04:22.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.366087 19491 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.157.193:34963 every 8 connection(s)
14:04:22.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.370487 19341 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/data/info.pb
14:04:22.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.372625 19063 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu as pid 19341
14:04:22.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.373061 19063 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-0/wal/instance
14:04:22.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.385143 19063 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:22.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:22.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/wal
14:04:22.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data
14:04:22.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:04:22.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:04:22.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:04:22.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:22.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/logs
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data/info.pb
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:04:22.389 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.194:0
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.18.157.194
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.194
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:22.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:04:22.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.463810 19495 heartbeater.cc:344] Connected to a master server at 127.18.157.253:44395
14:04:22.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.464465 19495 heartbeater.cc:461] Registering TS with master...
14:04:22.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.466085 19495 heartbeater.cc:507] Master 127.18.157.253:44395 requested a full tablet report, sending...
14:04:22.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.471019 19183 ts_manager.cc:194] Registered new tserver with Master: 82665e2d98ce4566b45a9d183c9a0493 (127.18.157.193:34963)
14:04:22.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.490731 19499 heartbeater.cc:344] Connected to a master server at 127.18.157.254:40441
14:04:22.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.491124 19499 heartbeater.cc:461] Registering TS with master...
14:04:22.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.492015 19499 heartbeater.cc:507] Master 127.18.157.254:40441 requested a full tablet report, sending...
14:04:22.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.492322 19492 heartbeater.cc:344] Connected to a master server at 127.18.157.252:39873
14:04:22.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.492745 19492 heartbeater.cc:461] Registering TS with master...
14:04:22.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.493721 19492 heartbeater.cc:507] Master 127.18.157.252:39873 requested a full tablet report, sending...
14:04:22.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.495061 19107 ts_manager.cc:194] Registered new tserver with Master: 82665e2d98ce4566b45a9d183c9a0493 (127.18.157.193:34963)
14:04:22.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.496918 19261 ts_manager.cc:194] Registered new tserver with Master: 82665e2d98ce4566b45a9d183c9a0493 (127.18.157.193:34963)
14:04:22.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.501036 19261 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.18.157.193:36407
14:04:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:22.880337 19497 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:04:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:22.880986 19497 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:04:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:22.881407 19497 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:04:22.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:22.916746 19497 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:04:22.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:22.917784 19497 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.18.157.194
14:04:22.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.959813 19497 tablet_server_runner.cc:78] Tablet server non-default flags:
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/wal
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.194:0
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:22.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:22.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data/info.pb
14:04:22.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.194
14:04:22.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:22.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.18.157.194
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/logs
14:04:22.965 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:22 UTC on e7f111948823
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11959
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.961576 19497 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:04:22.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:22.963943 19497 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:04:22.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:22.980769 19509 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:04:23.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:23.506036 19492 heartbeater.cc:499] Master 127.18.157.252:39873 was elected leader, sending a full tablet report...
14:04:25.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:22.982613 19510 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:04:25.027 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:24.383404 19508 debug-util.cc:398] Leaking SignalData structure 0x7b08000271c0 after lost signal to thread 19497
14:04:25.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.028255 19497 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 2.046s	user 0.683s	sys 1.261s
14:04:25.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.028589 19497 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 2.047s	user 0.683s	sys 1.261s
14:04:25.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.028884 19511 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 2045 milliseconds
14:04:25.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.030144 19512 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:04:25.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.030072 19497 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:04:25.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.033951 19497 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:04:25.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.036051 19497 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:04:25.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.037411 19497 hybrid_clock.cc:648] HybridClock initialized: now 1779113065037395 us; error 49 us; skew 500 ppm
14:04:25.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.038199 19497 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:04:25.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.044111 19497 webserver.cc:533] Webserver started at http://127.18.157.194:40283/ using document root <none> and password file <none>
14:04:25.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.045023 19497 fs_manager.cc:362] Metadata directory not provided
14:04:25.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.045266 19497 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:04:25.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.045670 19497 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:04:25.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.049942 19497 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data/instance:
14:04:25.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c428ab809ee84419857874aa02ad3b5e"
14:04:25.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:25 on dist-test-slave-ktkl"
14:04:25.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.051035 19497 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/wal/instance:
14:04:25.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c428ab809ee84419857874aa02ad3b5e"
14:04:25.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:25 on dist-test-slave-ktkl"
14:04:25.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.058518 19497 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.007s	sys 0.000s
14:04:25.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.063958 19522 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:04:25.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.065089 19497 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.000s
14:04:25.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.065368 19497 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data,/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/wal
14:04:25.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c428ab809ee84419857874aa02ad3b5e"
14:04:25.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:25 on dist-test-slave-ktkl"
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.065652 19497 fs_report.cc:389] FS layout report
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/wal
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/wal
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data/data
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:04:25.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:04:25.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:04:25.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:04:25.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:04:25.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.137887 19497 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:04:25.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.139564 19497 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:04:25.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.140085 19497 kserver.cc:163] Server-wide thread pool size limit: 3276
14:04:25.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.143208 19497 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:04:25.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.148343 19497 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:04:25.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.148582 19497 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:04:25.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.148890 19497 ts_tablet_manager.cc:616] Registered 0 tablets
14:04:25.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.149104 19497 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:04:25.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.299139 19497 rpc_server.cc:307] RPC server started. Bound to: 127.18.157.194:38201
14:04:25.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.299247 19635 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.157.194:38201 every 8 connection(s)
14:04:25.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.302074 19497 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/data/info.pb
14:04:25.312 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.311539 19063 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu as pid 19497
14:04:25.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.312760 19063 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-1/wal/instance
14:04:25.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.326781 19063 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:25.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu
14:04:25.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/wal
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/logs
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data/info.pb
14:04:25.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.195:0
14:04:25.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.18.157.195
14:04:25.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.195
14:04:25.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:25.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:25.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:25.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:25.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:25.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:04:25.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.360788 19637 heartbeater.cc:344] Connected to a master server at 127.18.157.253:44395
14:04:25.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.361346 19637 heartbeater.cc:461] Registering TS with master...
14:04:25.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.361521 19636 heartbeater.cc:344] Connected to a master server at 127.18.157.252:39873
14:04:25.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.362659 19636 heartbeater.cc:461] Registering TS with master...
14:04:25.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.364488 19637 heartbeater.cc:507] Master 127.18.157.253:44395 requested a full tablet report, sending...
14:04:25.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.364727 19636 heartbeater.cc:507] Master 127.18.157.252:39873 requested a full tablet report, sending...
14:04:25.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.368862 19183 ts_manager.cc:194] Registered new tserver with Master: c428ab809ee84419857874aa02ad3b5e (127.18.157.194:38201)
14:04:25.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.369212 19261 ts_manager.cc:194] Registered new tserver with Master: c428ab809ee84419857874aa02ad3b5e (127.18.157.194:38201)
14:04:25.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.371037 19261 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.18.157.194:55717
14:04:25.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.376988 19638 heartbeater.cc:344] Connected to a master server at 127.18.157.254:40441
14:04:25.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.377331 19638 heartbeater.cc:461] Registering TS with master...
14:04:25.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.377978 19638 heartbeater.cc:507] Master 127.18.157.254:40441 requested a full tablet report, sending...
14:04:25.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.380510 19107 ts_manager.cc:194] Registered new tserver with Master: c428ab809ee84419857874aa02ad3b5e (127.18.157.194:38201)
14:04:25.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.679947 19646 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:04:25.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.680536 19646 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:04:25.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.681114 19646 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:04:25.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.715807 19646 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:04:25.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.716961 19646 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.18.157.195
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.757704 19646 tablet_server_runner.cc:78] Tablet server non-default flags:
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.18.157.212:36697
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/wal
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.18.157.195:0
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:04:25.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data/info.pb
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.18.157.195
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.18.157.254:40441,127.18.157.253:44395,127.18.157.252:39873
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.18.157.195
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/logs
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66827b15b158c37363c37baa160f29a0ca1f1e63
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 13:43:22 UTC on e7f111948823
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 11959
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:04:25.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.759117 19646 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:04:25.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:25.760667 19646 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:04:25.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.773954 19652 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:04:26.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:26.374675 19636 heartbeater.cc:499] Master 127.18.157.252:39873 was elected leader, sending a full tablet report...
14:04:26.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:25.777160 19653 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:04:27.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:26.999953 19654 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1223 milliseconds
14:04:27.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:27.000504 19655 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:04:27.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.000906 19646 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:04:27.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.004802 19646 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:04:27.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.007190 19646 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:04:27.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.008565 19646 hybrid_clock.cc:648] HybridClock initialized: now 1779113067008499 us; error 99 us; skew 500 ppm
14:04:27.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.009509 19646 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:04:27.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.016006 19646 webserver.cc:533] Webserver started at http://127.18.157.195:35149/ using document root <none> and password file <none>
14:04:27.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.016949 19646 fs_manager.cc:362] Metadata directory not provided
14:04:27.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.017189 19646 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:04:27.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.017720 19646 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:04:27.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.023172 19646 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data/instance:
14:04:27.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57"
14:04:27.023 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:27 on dist-test-slave-ktkl"
14:04:27.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.024319 19646 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/wal/instance:
14:04:27.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57"
14:04:27.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:27 on dist-test-slave-ktkl"
14:04:27.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.031806 19646 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.008s	sys 0.000s
14:04:27.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.037120 19663 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:04:27.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.038048 19646 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.002s	sys 0.000s
14:04:27.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.038415 19646 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data,/tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/wal
14:04:27.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57"
14:04:27.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:04:27 on dist-test-slave-ktkl"
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.038762 19646 fs_report.cc:389] FS layout report
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/wal
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/wal
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data/data
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:04:27.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:04:27.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:04:27.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:04:27.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.083429 19646 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:04:27.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.085016 19646 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:04:27.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.085533 19646 kserver.cc:163] Server-wide thread pool size limit: 3276
14:04:27.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.088806 19646 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:04:27.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.093084 19646 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:04:27.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.093317 19646 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:04:27.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.093647 19646 ts_tablet_manager.cc:616] Registered 0 tablets
14:04:27.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.093842 19646 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:04:27.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.240664 19646 rpc_server.cc:307] RPC server started. Bound to: 127.18.157.195:37969
14:04:27.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.240760 19776 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.18.157.195:37969 every 8 connection(s)
14:04:27.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.244050 19646 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/data/info.pb
14:04:27.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.248757 19063 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu as pid 19646
14:04:27.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.249230 19063 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskqJwMAx/test-tmp/mini-kudu-cluster9953448344576637607/ts-2/wal/instance
14:04:27.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.291617 19778 heartbeater.cc:344] Connected to a master server at 127.18.157.253:44395
14:04:27.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.292111 19778 heartbeater.cc:461] Registering TS with master...
14:04:27.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.293419 19778 heartbeater.cc:507] Master 127.18.157.253:44395 requested a full tablet report, sending...
14:04:27.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.294154 19779 heartbeater.cc:344] Connected to a master server at 127.18.157.254:40441
14:04:27.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.294552 19779 heartbeater.cc:461] Registering TS with master...
14:04:27.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.295428 19779 heartbeater.cc:507] Master 127.18.157.254:40441 requested a full tablet report, sending...
14:04:27.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.297014 19183 ts_manager.cc:194] Registered new tserver with Master: b10ee770a35a4b4ebe0b3ff9c5184b57 (127.18.157.195:37969)
14:04:27.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.297962 19107 ts_manager.cc:194] Registered new tserver with Master: b10ee770a35a4b4ebe0b3ff9c5184b57 (127.18.157.195:37969)
14:04:27.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.299127 19777 heartbeater.cc:344] Connected to a master server at 127.18.157.252:39873
14:04:27.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.299504 19777 heartbeater.cc:461] Registering TS with master...
14:04:27.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.300808 19777 heartbeater.cc:507] Master 127.18.157.252:39873 requested a full tablet report, sending...
14:04:27.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.303349 19261 ts_manager.cc:194] Registered new tserver with Master: b10ee770a35a4b4ebe0b3ff9c5184b57 (127.18.157.195:37969)
14:04:27.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.305521 19261 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.18.157.195:59555
14:04:27.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:27.313616 19063 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
14:04:27.325 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:04:27.326 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

14:04:27.334 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.18.157.254"
      port: 40441
    }
    bound_http_address {
      host: "127.18.157.254"
      port: 44269
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.18.157.253"
      port: 44395
    }
    bound_http_address {
      host: "127.18.157.253"
      port: 44115
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.18.157.252"
      port: 39873
    }
    bound_http_address {
      host: "127.18.157.252"
      port: 39687
    }
  }
}

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

14:04:27.339 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.18.157.193"
      port: 34963
    }
    bound_http_address {
      host: "127.18.157.193"
      port: 38331
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.18.157.194"
      port: 38201
    }
    bound_http_address {
      host: "127.18.157.194"
      port: 40283
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.18.157.195"
      port: 37969
    }
    bound_http_address {
      host: "127.18.157.195"
      port: 35149
    }
  }
}

14:04:27.339 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
14:04:27.540 [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:04:27.603 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
14:04:27.764 [DEBUG - main] (Connection.java:748) [peer master-127.18.157.254:40441(127.18.157.254:40441)] connecting to peer
14:04:27.819 [DEBUG - main] (Connection.java:748) [peer master-127.18.157.253:44395(127.18.157.253:44395)] connecting to peer
14:04:27.821 [DEBUG - main] (Connection.java:748) [peer master-127.18.157.252:39873(127.18.157.252:39873)] connecting to peer
14:04:27.840 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.18.157.253:44395(127.18.157.253:44395)] Successfully connected to peer
14:04:27.840 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.18.157.252:39873(127.18.157.252:39873)] Successfully connected to peer
14:04:27.841 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.18.157.254:40441(127.18.157.254:40441)] Successfully connected to peer
14:04:27.936 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.18.157.254
14:04:27.939 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.18.157.253
14:04:27.944 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.18.157.252
14:04:28.156 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0x4b755fd8, L:/127.0.0.1:41952 - R:/127.18.157.252:39873] using SASL/PLAIN
14:04:28.156 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x008f667e, L:/127.0.0.1:49858 - R:/127.18.157.253:44395] using SASL/PLAIN
14:04:28.156 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x6caafe11, L:/127.0.0.1:52098 - R:/127.18.157.254:40441] using SASL/PLAIN
14:04:28.194 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
14:04:28.195 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=468), Traces: [0ms] refreshing cache from master, [25ms] Sub RPC ConnectToMaster: sending RPC to server master-127.18.157.254:40441, [89ms] Sub RPC ConnectToMaster: sending RPC to server master-127.18.157.253:44395, [91ms] Sub RPC ConnectToMaster: sending RPC to server master-127.18.157.252:39873, [441ms] Sub RPC ConnectToMaster: received response from server master-127.18.157.254:40441: OK, [441ms] Sub RPC ConnectToMaster: received response from server master-127.18.157.253:44395: OK, [442ms] Sub RPC ConnectToMaster: received response from server master-127.18.157.252:39873: OK, deferred=null) after lookup
14:04:28.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.249830 19261 catalog_manager.cc:2282] Servicing CreateTable request from {username='slave'} at 127.0.0.1:41952:
14:04:28.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1779113052372"
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
14:04:28.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:04:28.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:28.253633 19261 catalog_manager.cc:7058] The number of live tablet servers is not enough to re-replicate a tablet replica of the newly created table org.apache.kudu.client.TestHybridTime-1779113052372 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
14:04:28.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.309527 19777 heartbeater.cc:499] Master 127.18.157.252:39873 was elected leader, sending a full tablet report...
14:04:28.329 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:894) Opened table ab7f14963f684230aa202ac66d8102d7
14:04:28.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.338519 19427 tablet_service.cc:1511] Processing CreateTablet for tablet 5dd5972193b5499cbf731d5befcddcd0 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779113052372 [id=ab7f14963f684230aa202ac66d8102d7]), partition=RANGE (key) PARTITION UNBOUNDED
14:04:28.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.340335 19427 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 5dd5972193b5499cbf731d5befcddcd0. 1 dirs total, 0 dirs full, 0 dirs failed
14:04:28.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.351128 19571 tablet_service.cc:1511] Processing CreateTablet for tablet 5dd5972193b5499cbf731d5befcddcd0 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779113052372 [id=ab7f14963f684230aa202ac66d8102d7]), partition=RANGE (key) PARTITION UNBOUNDED
14:04:28.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.353305 19571 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 5dd5972193b5499cbf731d5befcddcd0. 1 dirs total, 0 dirs full, 0 dirs failed
14:04:28.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.352806 19711 tablet_service.cc:1511] Processing CreateTablet for tablet 5dd5972193b5499cbf731d5befcddcd0 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779113052372 [id=ab7f14963f684230aa202ac66d8102d7]), partition=RANGE (key) PARTITION UNBOUNDED
14:04:28.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.354544 19711 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 5dd5972193b5499cbf731d5befcddcd0. 1 dirs total, 0 dirs full, 0 dirs failed
14:04:28.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.377172 19811 tablet_bootstrap.cc:492] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493: Bootstrap starting.
14:04:28.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.387296 19811 tablet_bootstrap.cc:654] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493: Neither blocks nor log segments found. Creating new log.
14:04:28.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.389685 19811 log.cc:826] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493: Log is configured to *not* fsync() on all Append() calls
14:04:28.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.408134 19812 tablet_bootstrap.cc:492] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e: Bootstrap starting.
14:04:28.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.416019 19811 tablet_bootstrap.cc:492] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493: No bootstrap required, opened a new log
14:04:28.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.416924 19811 ts_tablet_manager.cc:1403] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493: Time spent bootstrapping tablet: real 0.040s	user 0.018s	sys 0.015s
14:04:28.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.417054 19812 tablet_bootstrap.cc:654] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e: Neither blocks nor log segments found. Creating new log.
14:04:28.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.419831 19812 log.cc:826] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e: Log is configured to *not* fsync() on all Append() calls
14:04:28.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.426674 19814 tablet_bootstrap.cc:492] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57: Bootstrap starting.
14:04:28.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.430160 19812 tablet_bootstrap.cc:492] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e: No bootstrap required, opened a new log
14:04:28.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.430698 19812 ts_tablet_manager.cc:1403] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e: Time spent bootstrapping tablet: real 0.023s	user 0.015s	sys 0.004s
14:04:28.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.438519 19814 tablet_bootstrap.cc:654] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57: Neither blocks nor log segments found. Creating new log.
14:04:28.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.441250 19814 log.cc:826] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57: Log is configured to *not* fsync() on all Append() calls
14:04:28.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.448026 19814 tablet_bootstrap.cc:492] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57: No bootstrap required, opened a new log
14:04:28.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.448915 19814 ts_tablet_manager.cc:1403] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57: Time spent bootstrapping tablet: real 0.023s	user 0.012s	sys 0.006s
14:04:28.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.452896 19812 raft_consensus.cc:359] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.452883 19811 raft_consensus.cc:359] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.453794 19811 raft_consensus.cc:385] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:04:28.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.453788 19812 raft_consensus.cc:385] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:04:28.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.454088 19812 raft_consensus.cc:740] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c428ab809ee84419857874aa02ad3b5e, State: Initialized, Role: FOLLOWER
14:04:28.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.454093 19811 raft_consensus.cc:740] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 82665e2d98ce4566b45a9d183c9a0493, State: Initialized, Role: FOLLOWER
14:04:28.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.455067 19812 consensus_queue.cc:260] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [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: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.455935 19811 consensus_queue.cc:260] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [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: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.460 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.459856 19812 ts_tablet_manager.cc:1434] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e: Time spent starting tablet: real 0.029s	user 0.024s	sys 0.004s
14:04:28.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.471006 19811 ts_tablet_manager.cc:1434] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493: Time spent starting tablet: real 0.053s	user 0.031s	sys 0.011s
14:04:28.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.486932 19814 raft_consensus.cc:359] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.487871 19814 raft_consensus.cc:385] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:04:28.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.488160 19814 raft_consensus.cc:740] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: b10ee770a35a4b4ebe0b3ff9c5184b57, State: Initialized, Role: FOLLOWER
14:04:28.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.489091 19814 consensus_queue.cc:260] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [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: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:28.491245 19500 tablet.cc:2404] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:04:28.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.495133 19814 ts_tablet_manager.cc:1434] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57: Time spent starting tablet: real 0.044s	user 0.022s	sys 0.015s
14:04:28.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:28.539705 19784 tablet.cc:2404] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:04:28.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:04:28.591647 19643 tablet.cc:2404] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:04:28.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.754063 19817 raft_consensus.cc:493] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:04:28.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.754648 19817 raft_consensus.cc:515] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.757225 19817 leader_election.cc:290] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 82665e2d98ce4566b45a9d183c9a0493 (127.18.157.193:34963), b10ee770a35a4b4ebe0b3ff9c5184b57 (127.18.157.195:37969)
14:04:28.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.769254 19447 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "5dd5972193b5499cbf731d5befcddcd0" candidate_uuid: "c428ab809ee84419857874aa02ad3b5e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "82665e2d98ce4566b45a9d183c9a0493" is_pre_election: true
14:04:28.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.769662 19731 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "5dd5972193b5499cbf731d5befcddcd0" candidate_uuid: "c428ab809ee84419857874aa02ad3b5e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" is_pre_election: true
14:04:28.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.770227 19447 raft_consensus.cc:2468] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate c428ab809ee84419857874aa02ad3b5e in term 0.
14:04:28.770 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.770512 19731 raft_consensus.cc:2468] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate c428ab809ee84419857874aa02ad3b5e in term 0.
14:04:28.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.771608 19523 leader_election.cc:304] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [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: 82665e2d98ce4566b45a9d183c9a0493, c428ab809ee84419857874aa02ad3b5e; no voters: 
14:04:28.772 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.772392 19817 raft_consensus.cc:2804] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Leader pre-election won for term 1
14:04:28.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.772717 19817 raft_consensus.cc:493] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:04:28.773 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.772946 19817 raft_consensus.cc:3060] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 0 FOLLOWER]: Advancing to term 1
14:04:28.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.777685 19817 raft_consensus.cc:515] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.779138 19817 leader_election.cc:290] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [CANDIDATE]: Term 1 election: Requested vote from peers 82665e2d98ce4566b45a9d183c9a0493 (127.18.157.193:34963), b10ee770a35a4b4ebe0b3ff9c5184b57 (127.18.157.195:37969)
14:04:28.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.780036 19731 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "5dd5972193b5499cbf731d5befcddcd0" candidate_uuid: "c428ab809ee84419857874aa02ad3b5e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57"
14:04:28.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.780098 19447 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "5dd5972193b5499cbf731d5befcddcd0" candidate_uuid: "c428ab809ee84419857874aa02ad3b5e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "82665e2d98ce4566b45a9d183c9a0493"
14:04:28.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.780489 19447 raft_consensus.cc:3060] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [term 0 FOLLOWER]: Advancing to term 1
14:04:28.780 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.780501 19731 raft_consensus.cc:3060] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [term 0 FOLLOWER]: Advancing to term 1
14:04:28.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.786098 19447 raft_consensus.cc:2468] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate c428ab809ee84419857874aa02ad3b5e in term 1.
14:04:28.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.786211 19731 raft_consensus.cc:2468] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate c428ab809ee84419857874aa02ad3b5e in term 1.
14:04:28.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.787321 19526 leader_election.cc:304] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [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: b10ee770a35a4b4ebe0b3ff9c5184b57, c428ab809ee84419857874aa02ad3b5e; no voters: 
14:04:28.788 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.788040 19817 raft_consensus.cc:2804] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 1 FOLLOWER]: Leader election won for term 1
14:04:28.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.790671 19817 raft_consensus.cc:697] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [term 1 LEADER]: Becoming Leader. State: Replica: c428ab809ee84419857874aa02ad3b5e, State: Running, Role: LEADER
14:04:28.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.791816 19817 consensus_queue.cc:237] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [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: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } }
14:04:28.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:28.803380 19260 catalog_manager.cc:5696] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e reported cstate change: term changed from 0 to 1, leader changed from <none> to c428ab809ee84419857874aa02ad3b5e (127.18.157.194). New cstate: current_term: 1 leader_uuid: "c428ab809ee84419857874aa02ad3b5e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "c428ab809ee84419857874aa02ad3b5e" member_type: VOTER last_known_addr { host: "127.18.157.194" port: 38201 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 } health_report { overall_health: UNKNOWN } } }
14:04:28.881 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet 5dd5972193b5499cbf731d5befcddcd0 for table 'org.apache.kudu.client.TestHybridTime-1779113052372' with partition [<start>, <end>)
14:04:28.889 [DEBUG - kudu-nio-2] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299992, tablet=5dd5972193b5499cbf731d5befcddcd0@[82665e2d98ce4566b45a9d183c9a0493(127.18.157.193:34963),b10ee770a35a4b4ebe0b3ff9c5184b57(127.18.157.195:37969),c428ab809ee84419857874aa02ad3b5e(127.18.157.194:38201)[L]]}]
14:04:28.891 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=21), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.18.157.252:39873, [10ms] Sub RPC GetTableLocations: received response from server master-127.18.157.252:39873: OK, deferred=null) after lookup
14:04:28.903 [DEBUG - kudu-nio-2] (Connection.java:748) [peer c428ab809ee84419857874aa02ad3b5e(127.18.157.194:38201)] connecting to peer
14:04:28.906 [DEBUG - kudu-nio-3] (Connection.java:757) [peer c428ab809ee84419857874aa02ad3b5e(127.18.157.194:38201)] Successfully connected to peer
14:04:28.992 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x4905bdc1, L:/127.0.0.1:54112 - R:/127.18.157.194:38201] using TOKEN/null
14:04:29.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.005851 19731 raft_consensus.cc:1275] T 5dd5972193b5499cbf731d5befcddcd0 P b10ee770a35a4b4ebe0b3ff9c5184b57 [term 1 FOLLOWER]: Refusing update from remote peer c428ab809ee84419857874aa02ad3b5e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:04:29.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.005843 19447 raft_consensus.cc:1275] T 5dd5972193b5499cbf731d5befcddcd0 P 82665e2d98ce4566b45a9d183c9a0493 [term 1 FOLLOWER]: Refusing update from remote peer c428ab809ee84419857874aa02ad3b5e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:04:29.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.007895 19822 consensus_queue.cc:1048] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [LEADER]: Connected to new peer: Peer: permanent_uuid: "b10ee770a35a4b4ebe0b3ff9c5184b57" member_type: VOTER last_known_addr { host: "127.18.157.195" port: 37969 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:04:29.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.008569 19817 consensus_queue.cc:1048] T 5dd5972193b5499cbf731d5befcddcd0 P c428ab809ee84419857874aa02ad3b5e [LEADER]: Connected to new peer: Peer: permanent_uuid: "82665e2d98ce4566b45a9d183c9a0493" member_type: VOTER last_known_addr { host: "127.18.157.193" port: 34963 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:04:29.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.037878 19835 mvcc.cc:204] Tried to move back new op lower bound from 7287247130632081408 to 7287247129780719616. Current Snapshot: MvccSnapshot[applied={T|T < 7287247130632081408}]
14:04:29.137 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:04:29.156 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:04:29.171 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:04:29.176 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@999035250(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:04:29.198 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:04:29.199 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@116065663(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:04:29.218 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:04:29.218 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@446568444(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:04:29.236 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:04:29.340 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.353 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7287247151112081409) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.364 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7287247151112081413) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.376 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7287247151112081417) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.385 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7287247151112081421) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.399 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7287247151112081425) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.411 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7287247151112081429) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.422 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7287247151112081430) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779113052372, tablet=5dd5972193b5499cbf731d5befcddcd0, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:04:29.431 [DEBUG - main] (Connection.java:575) [peer master-127.18.157.254:40441(127.18.157.254:40441)] disconnecting while in state READY
14:04:29.432 [DEBUG - main] (Connection.java:575) [peer c428ab809ee84419857874aa02ad3b5e(127.18.157.194:38201)] disconnecting while in state READY
14:04:29.437 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.18.157.254:40441(127.18.157.254:40441)] handling channelInactive
14:04:29.438 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.18.157.254:40441(127.18.157.254:40441)] cleaning up while in state READY due to: connection closed
14:04:29.440 [DEBUG - main] (Connection.java:575) [peer master-127.18.157.253:44395(127.18.157.253:44395)] disconnecting while in state READY
14:04:29.440 [DEBUG - main] (Connection.java:575) [peer master-127.18.157.252:39873(127.18.157.252:39873)] disconnecting while in state READY
14:04:29.441 [DEBUG - kudu-nio-3] (Connection.java:239) [peer c428ab809ee84419857874aa02ad3b5e(127.18.157.194:38201)] handling channelInactive
14:04:29.441 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.18.157.253:44395(127.18.157.253:44395)] handling channelInactive
14:04:29.441 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.18.157.253:44395(127.18.157.253:44395)] cleaning up while in state READY due to: connection closed
14:04:29.441 [DEBUG - kudu-nio-3] (Connection.java:709) [peer c428ab809ee84419857874aa02ad3b5e(127.18.157.194:38201)] cleaning up while in state READY due to: connection closed
14:04:29.442 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2704) Releasing all remaining resources
14:04:29.451 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.18.157.252:39873(127.18.157.252:39873)] handling channelInactive
14:04:29.451 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.18.157.252:39873(127.18.157.252:39873)] cleaning up while in state READY due to: connection closed
14:04:29.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.452252 19063 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu with pid 19341
14:04:29.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.493139 19063 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu with pid 19497
14:04:29.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.539089 19063 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu with pid 19646
14:04:29.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.572680 19063 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu with pid 19075
14:04:29.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.599781 19063 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu with pid 19145
14:04:29.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:04:29.626308 19063 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskqJwMAx/build/tsan/bin/kudu with pid 19220
14:04:30.789 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:04:30Z chronyd exiting

Time: 19.676

OK (1 test)

