JUnit version 4.13.2
.20:03:28.582 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1783541008573
20:03:28.606 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
20:03:28.665 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
20:03:28.674 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskP3hnwj/test-tmp
20:03:28.679 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
20:03:28.681 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskP3hnwj/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
20:03:28.914 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

20:03:29.093 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
20:03:29.095 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

20:03:29.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.099166 21296 mini_oidc.cc:150] Starting JWKS server
20:03:29.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.102954 21296 webserver.cc:533] Webserver started at http://127.0.0.1:40419/ using document root <none> and password file <none>
20:03:29.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.103515 21296 mini_oidc.cc:186] Starting OIDC Discovery server
20:03:29.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.105962 21296 webserver.cc:533] Webserver started at http://127.0.0.1:35291/ using document root <none> and password file <none>
20:03:29.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T20:03:29Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
20:03:29.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T20:03:29Z Disabled control of system clock
20:03:29.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.168056 21296 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:29.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:29.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/wal
20:03:29.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data
20:03:29.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:29.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:29.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:29.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:29.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:29.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:29.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/logs
20:03:29.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data/info.pb
20:03:29.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:29.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:29.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:29.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:29.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:29.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:29.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:29.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:29.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
20:03:29.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:29.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:29.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:29.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.62:38711
20:03:29.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.62
20:03:29.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:29.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:29.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:29.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:29.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:29.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:29.176 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:29.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:29.177 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40419/jwks.json with env {}
20:03:29.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.471364 21308 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:29.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.471926 21308 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:29.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.472565 21308 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:29.510 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.509657 21308 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
20:03:29.510 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.509965 21308 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:29.510 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.510234 21308 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
20:03:29.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.510440 21308 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
20:03:29.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.510627 21308 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
20:03:29.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.510812 21308 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:40419/jwks.json
20:03:29.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.548300 21308 master_runner.cc:387] Master server non-default flags:
20:03:29.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:29.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:29.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:29.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:29.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data
20:03:29.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/wal
20:03:29.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:29.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:29.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:29.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:29.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:29.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.62:38711
20:03:29.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:29.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:29.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:29.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40419/jwks.json
20:03:29.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:29.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:29.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data/info.pb
20:03:29.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.62
20:03:29.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:29.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:29.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:29.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:29.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:29.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:29.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/logs
20:03:29.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:29.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:29.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:29.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
20:03:29.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:29.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
20:03:29.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
20:03:29.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 19:43:23 UTC on e7f111948823
20:03:29.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12779
20:03:29.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
20:03:29.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.549665 21308 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:29.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:29.551522 21308 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:03:29.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.564286 21315 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
20:03:30.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:29.564292 21314 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
20:03:30.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:30.873548 21316 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1308 milliseconds
20:03:30.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:30.874202 21317 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
20:03:30.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:30.878402 21308 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.314s	user 0.411s	sys 0.898s
20:03:30.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:30.878669 21308 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.315s	user 0.411s	sys 0.898s
20:03:30.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.878850 21308 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
20:03:30.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.879951 21308 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:30.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.887959 21308 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:30.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.889467 21308 hybrid_clock.cc:648] HybridClock initialized: now 1783541010889391 us; error 89 us; skew 500 ppm
20:03:30.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.890488 21308 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
20:03:30.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.897796 21308 webserver.cc:533] Webserver started at http://127.20.204.62:42111/ using document root <none> and password file <none>
20:03:30.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.899055 21308 fs_manager.cc:362] Metadata directory not provided
20:03:30.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.899262 21308 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:30.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.899740 21308 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:30.904 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.904240 21308 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data/instance:
20:03:30.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c8c1b381797c494d80e57ea3626eb3d5"
20:03:30.905 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:30 on dist-test-slave-b0qh"
20:03:30.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.905601 21308 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/wal/instance:
20:03:30.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c8c1b381797c494d80e57ea3626eb3d5"
20:03:30.907 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:30 on dist-test-slave-b0qh"
20:03:30.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.915843 21308 fs_manager.cc:696] Time spent creating directory manager: real 0.010s	user 0.008s	sys 0.000s
20:03:30.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.922650 21324 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:30.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.924036 21308 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.001s	sys 0.003s
20:03:30.924 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.924433 21308 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data,/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/wal
20:03:30.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "c8c1b381797c494d80e57ea3626eb3d5"
20:03:30.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:30 on dist-test-slave-b0qh"
20:03:30.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:30.924907 21308 fs_report.cc:389] FS layout report
20:03:30.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:30.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/wal
20:03:30.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/wal
20:03:30.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data/data
20:03:30.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:30.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:30.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:30.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:30.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:30.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:30.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:30.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:30.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:30.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:30.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:31.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.012682 21308 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:31.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.014320 21308 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:03:31.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.014716 21308 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:31.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.085878 21308 rpc_server.cc:307] RPC server started. Bound to: 127.20.204.62:38711
20:03:31.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.085969 21375 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.20.204.62:38711 every 8 connection(s)
20:03:31.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.088900 21308 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/data/info.pb
20:03:31.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.095033 21376 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:31.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.095703 21296 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu as pid 21308
20:03:31.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.096223 21296 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-0/wal/instance
20:03:31.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.099946 21296 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:31.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:31.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/wal
20:03:31.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data
20:03:31.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:31.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:31.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:31.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:31.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:31.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:31.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/logs
20:03:31.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data/info.pb
20:03:31.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:31.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:31.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:31.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:31.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:31.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:31.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:31.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:31.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
20:03:31.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:31.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:31.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:31.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.61:35719
20:03:31.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.61
20:03:31.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:31.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:31.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:31.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:31.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:31.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:31.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:31.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:31.107 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40419/jwks.json with env {}
20:03:31.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.106712 21376 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } has no permanent_uuid. Determining permanent_uuid...
20:03:31.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.135015 21376 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } has no permanent_uuid. Determining permanent_uuid...
20:03:31.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.137647 21328 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:31.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.142026 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.61:35719: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:31.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.191571 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } attempt: 1
20:03:31.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.196141 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.61:35719: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:31.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.264658 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } attempt: 2
20:03:31.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.269538 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.61:35719: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:31.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.361016 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } attempt: 3
20:03:31.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.365382 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.61:35719: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:31.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.412521 21378 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:31.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.413136 21378 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:31.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.413864 21378 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:31.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.456369 21378 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
20:03:31.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.456671 21378 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:31.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.456864 21378 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
20:03:31.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.457065 21378 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
20:03:31.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.457249 21378 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
20:03:31.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.457387 21378 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:40419/jwks.json
20:03:31.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.493961 21378 master_runner.cc:387] Master server non-default flags:
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/wal
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:31.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:31.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:31.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:31.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.61:35719
20:03:31.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:31.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:31.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:31.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40419/jwks.json
20:03:31.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:31.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:31.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data/info.pb
20:03:31.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.61
20:03:31.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:31.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:31.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:31.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:31.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:31.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:31.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/logs
20:03:31.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:31.499 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:31.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:31.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
20:03:31.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:31.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
20:03:31.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
20:03:31.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 19:43:23 UTC on e7f111948823
20:03:31.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12779
20:03:31.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
20:03:31.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.495339 21378 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:31.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.496991 21378 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:03:31.509 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.508874 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } attempt: 4
20:03:31.512 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.511601 21388 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
20:03:31.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.514963 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.61:35719: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:31.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:31.814512 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } attempt: 5
20:03:31.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.825137 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.61:35719: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:32.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.372704 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } attempt: 6
20:03:32.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:32.400454 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.61:35719: Network error: Client connection negotiation failed: client connection to 127.20.204.61:35719: connect: Connection refused (error 111)
20:03:32.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:32.914033 21386 debug-util.cc:398] Leaking SignalData structure 0x7b0800028020 after lost signal to thread 21378
20:03:32.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:32.958451 21386 kernel_stack_watchdog.cc:198] Thread 21378 stuck at /home/jenkins-slave/workspace/build_and_test_flaky@2/src/kudu/util/thread.cc:642 for 397ms:
20:03:32.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
20:03:32.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
20:03:32.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
20:03:32.960 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
20:03:32.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:32.959136 21378 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.445s	user 0.484s	sys 0.955s
20:03:32.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:32.959556 21378 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.446s	user 0.484s	sys 0.955s
20:03:32.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:31.514174 21387 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
20:03:32.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:32.962694 21390 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
20:03:32.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:32.966106 21389 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1446 milliseconds
20:03:32.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.966174 21378 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
20:03:32.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.967419 21378 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:32.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.969733 21378 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:32.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.971104 21378 hybrid_clock.cc:648] HybridClock initialized: now 1783541012971042 us; error 103 us; skew 500 ppm
20:03:32.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.971865 21378 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
20:03:32.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.977900 21378 webserver.cc:533] Webserver started at http://127.20.204.61:35163/ using document root <none> and password file <none>
20:03:32.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.978896 21378 fs_manager.cc:362] Metadata directory not provided
20:03:32.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.979137 21378 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:32.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.979526 21378 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:32.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.983835 21378 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data/instance:
20:03:32.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "660bc04b93a34962b0c6705c4518172c"
20:03:32.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:32 on dist-test-slave-b0qh"
20:03:32.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.984901 21378 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/wal/instance:
20:03:32.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "660bc04b93a34962b0c6705c4518172c"
20:03:32.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:32 on dist-test-slave-b0qh"
20:03:32.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.992496 21378 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.001s	sys 0.008s
20:03:32.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.998312 21399 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:32.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.999470 21378 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.001s	sys 0.003s
20:03:33.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:32.999752 21378 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data,/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/wal
20:03:33.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "660bc04b93a34962b0c6705c4518172c"
20:03:33.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:32 on dist-test-slave-b0qh"
20:03:33.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.000080 21378 fs_report.cc:389] FS layout report
20:03:33.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:33.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/wal
20:03:33.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/wal
20:03:33.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data/data
20:03:33.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:33.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:33.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:33.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:33.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:33.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:33.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:33.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:33.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:33.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:33.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:33.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.052107 21378 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:33.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.053594 21378 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:03:33.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.054169 21378 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:33.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.126737 21378 rpc_server.cc:307] RPC server started. Bound to: 127.20.204.61:35719
20:03:33.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.126811 21450 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.20.204.61:35719 every 8 connection(s)
20:03:33.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.129704 21378 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/data/info.pb
20:03:33.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.135771 21451 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:33.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.135862 21296 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu as pid 21378
20:03:33.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.136312 21296 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-1/wal/instance
20:03:33.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.139240 21296 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:33.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:33.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/wal
20:03:33.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data
20:03:33.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:33.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:33.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:33.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:33.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/logs
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data/info.pb
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:33.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:33.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:33.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:33.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:33.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
20:03:33.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:33.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:33.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:33.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.60:37727
20:03:33.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.60
20:03:33.146 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:33.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:33.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:33.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:33.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:33.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:33.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:33.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:33.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40419/jwks.json with env {}
20:03:33.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.154523 21451 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } has no permanent_uuid. Determining permanent_uuid...
20:03:33.183 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.182461 21451 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } has no permanent_uuid. Determining permanent_uuid...
20:03:33.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.196496 21451 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } has no permanent_uuid. Determining permanent_uuid...
20:03:33.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.199229 21402 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.203310 21451 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.252838 21451 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 1
20:03:33.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.257265 21451 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.325698 21451 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 2
20:03:33.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.329939 21451 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.421453 21451 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 3
20:03:33.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.426096 21451 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.461129 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } attempt: 7
20:03:33.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.464236 21453 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:33.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.464923 21453 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:33.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.465740 21453 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:33.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.473059 21376 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } has no permanent_uuid. Determining permanent_uuid...
20:03:33.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.479140 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.499728 21453 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
20:03:33.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.500042 21453 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:33.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.500240 21453 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
20:03:33.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.500552 21453 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
20:03:33.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.500885 21453 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
20:03:33.501 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.501147 21453 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:40419/jwks.json
20:03:33.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.537629 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 1
20:03:33.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.540040 21453 master_runner.cc:387] Master server non-default flags:
20:03:33.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:33.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:33.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:33.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:33.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data
20:03:33.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/wal
20:03:33.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
20:03:33.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:33.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:33.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:33.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
20:03:33.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.60:37727
20:03:33.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
20:03:33.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:40419/jwks.json
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data/info.pb
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.60
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:33.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/logs
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
20:03:33.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 19:43:23 UTC on e7f111948823
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12779
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.541646 21453 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.542794 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.543732 21453 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:03:33.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.555330 21465 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
20:03:33.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.569548 21451 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 4
20:03:33.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.573848 21451 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.624233 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 2
20:03:33.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.631927 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.717634 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 3
20:03:33.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.725077 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.865742 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 4
20:03:33.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.873106 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:33.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:33.873284 21451 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 5
20:03:33.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.880342 21451 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:34.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.156708 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 5
20:03:34.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:34.165309 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:34.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.428021 21451 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 6
20:03:34.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:34.442548 21451 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:34.718 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.717864 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 6
20:03:34.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:34.725083 21376 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.20.204.60:37727: Network error: Client connection negotiation failed: client connection to 127.20.204.60:37727: connect: Connection refused (error 111)
20:03:34.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:33.555888 21466 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
20:03:34.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:34.855484 21468 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
20:03:34.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:34.857854 21467 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
20:03:34.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:34.858515 21453 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.303s	user 0.482s	sys 0.820s
20:03:34.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:34.858762 21453 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.303s	user 0.482s	sys 0.820s
20:03:34.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.858935 21453 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
20:03:34.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.860239 21453 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:34.863 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.862931 21453 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:34.864 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.864331 21453 hybrid_clock.cc:648] HybridClock initialized: now 1783541014864264 us; error 81 us; skew 500 ppm
20:03:34.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.865248 21453 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
20:03:34.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.872573 21453 webserver.cc:533] Webserver started at http://127.20.204.60:38067/ using document root <none> and password file <none>
20:03:34.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.873750 21453 fs_manager.cc:362] Metadata directory not provided
20:03:34.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.874073 21453 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:34.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.874648 21453 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:34.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.879145 21453 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data/instance:
20:03:34.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "2cb6c955523d4840b16d6f2f1d3c39f5"
20:03:34.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:34 on dist-test-slave-b0qh"
20:03:34.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.880456 21453 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/wal/instance:
20:03:34.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "2cb6c955523d4840b16d6f2f1d3c39f5"
20:03:34.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:34 on dist-test-slave-b0qh"
20:03:34.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.888638 21453 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.002s
20:03:34.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.895933 21479 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:34.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.897410 21453 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.002s	sys 0.003s
20:03:34.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.897843 21453 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data,/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/wal
20:03:34.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "2cb6c955523d4840b16d6f2f1d3c39f5"
20:03:34.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:34 on dist-test-slave-b0qh"
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.898355 21453 fs_report.cc:389] FS layout report
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/wal
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/wal
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data/data
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:34.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:34.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.992326 21453 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:34.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.994361 21453 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:03:34.994 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:34.994769 21453 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:35.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.065973 21453 rpc_server.cc:307] RPC server started. Bound to: 127.20.204.60:37727
20:03:35.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.066043 21531 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.20.204.60:37727 every 8 connection(s)
20:03:35.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.069384 21453 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/data/info.pb
20:03:35.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.075590 21532 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:35.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.075582 21296 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu as pid 21453
20:03:35.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.075908 21296 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/master-2/wal/instance
20:03:35.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.094017 21532 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } has no permanent_uuid. Determining permanent_uuid...
20:03:35.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.131618 21532 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } has no permanent_uuid. Determining permanent_uuid...
20:03:35.149 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.149068 21532 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } has no permanent_uuid. Determining permanent_uuid...
20:03:35.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.165337 21532 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5: Bootstrap starting.
20:03:35.172 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.171739 21532 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5: Neither blocks nor log segments found. Creating new log.
20:03:35.173 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.173470 21532 log.cc:826] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5: Log is configured to *not* fsync() on all Append() calls
20:03:35.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.178416 21532 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5: No bootstrap required, opened a new log
20:03:35.197 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.196731 21532 raft_consensus.cc:359] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.197365 21532 raft_consensus.cc:385] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:35.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.197554 21532 raft_consensus.cc:740] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 2cb6c955523d4840b16d6f2f1d3c39f5, State: Initialized, Role: FOLLOWER
20:03:35.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.198565 21532 consensus_queue.cc:260] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [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: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.201809 21542 sys_catalog.cc:455] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [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: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:35.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.202561 21542 sys_catalog.cc:458] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: This master's current role is: FOLLOWER
20:03:35.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.203150 21532 sys_catalog.cc:565] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: configured and running, proceeding with master startup.
20:03:35.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:35.230652 21553 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
20:03:35.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:35.231504 21553 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
20:03:35.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.223479 21453 master_runner.cc:187] Error getting master registration for 127.20.204.62:38711: OK, instance_id { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" instance_seqno: 1783541011066724 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
20:03:35.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.245749 21453 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
20:03:35.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:35.249917 21485 tablet.cc:2406] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:35.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.503190 21451 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 7
20:03:35.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.521003 21451 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: Bootstrap starting.
20:03:35.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.526613 21451 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: Neither blocks nor log segments found. Creating new log.
20:03:35.528 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.528311 21451 log.cc:826] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: Log is configured to *not* fsync() on all Append() calls
20:03:35.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.532799 21451 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: No bootstrap required, opened a new log
20:03:35.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.552096 21451 raft_consensus.cc:359] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.552750 21451 raft_consensus.cc:385] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:35.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.552945 21451 raft_consensus.cc:740] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 660bc04b93a34962b0c6705c4518172c, State: Initialized, Role: FOLLOWER
20:03:35.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.553735 21451 consensus_queue.cc:260] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [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: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.556716 21557 sys_catalog.cc:455] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [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: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:35.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.557548 21557 sys_catalog.cc:458] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [sys.catalog]: This master's current role is: FOLLOWER
20:03:35.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.558530 21451 sys_catalog.cc:565] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [sys.catalog]: configured and running, proceeding with master startup.
20:03:35.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.559063 21542 raft_consensus.cc:493] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
20:03:35.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.559580 21542 raft_consensus.cc:515] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:35.562068 21405 tablet.cc:2406] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:35.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.562188 21542 leader_election.cc:290] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers c8c1b381797c494d80e57ea3626eb3d5 (127.20.204.62:38711), 660bc04b93a34962b0c6705c4518172c (127.20.204.61:35719)
20:03:35.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.565382 21426 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "660bc04b93a34962b0c6705c4518172c" is_pre_election: true
20:03:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.566334 21426 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 2cb6c955523d4840b16d6f2f1d3c39f5 in term 0.
20:03:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.567934 21483 leader_election.cc:304] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [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: 2cb6c955523d4840b16d6f2f1d3c39f5, 660bc04b93a34962b0c6705c4518172c; no voters: 
20:03:35.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.568991 21542 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Leader pre-election won for term 1
20:03:35.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.569912 21542 raft_consensus.cc:493] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
20:03:35.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.570513 21542 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 0 FOLLOWER]: Advancing to term 1
20:03:35.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.582296 21542 raft_consensus.cc:515] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.590327 21426 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "660bc04b93a34962b0c6705c4518172c"
20:03:35.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.590936 21426 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [term 0 FOLLOWER]: Advancing to term 1
20:03:35.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.593114 21542 leader_election.cc:290] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [CANDIDATE]: Term 1 election: Requested vote from peers c8c1b381797c494d80e57ea3626eb3d5 (127.20.204.62:38711), 660bc04b93a34962b0c6705c4518172c (127.20.204.61:35719)
20:03:35.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.598320 21426 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 2cb6c955523d4840b16d6f2f1d3c39f5 in term 1.
20:03:35.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.600320 21483 leader_election.cc:304] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [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: 2cb6c955523d4840b16d6f2f1d3c39f5, 660bc04b93a34962b0c6705c4518172c; no voters: 
20:03:35.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.601291 21542 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 1 FOLLOWER]: Leader election won for term 1
20:03:35.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.603480 21542 raft_consensus.cc:697] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [term 1 LEADER]: Becoming Leader. State: Replica: 2cb6c955523d4840b16d6f2f1d3c39f5, State: Running, Role: LEADER
20:03:35.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.604758 21542 consensus_queue.cc:237] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [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: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.614291 21565 sys_catalog.cc:455] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 2cb6c955523d4840b16d6f2f1d3c39f5. Latest consensus state: current_term: 1 leader_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:35.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.615088 21565 sys_catalog.cc:458] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: This master's current role is: LEADER
20:03:35.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.627712 21571 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
20:03:35.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.635426 21571 catalog_manager.cc:1520] Initializing Kudu cluster ID...
20:03:35.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.614907 21378 master_runner.cc:187] Error getting master registration for 127.20.204.62:38711: OK, instance_id { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" instance_seqno: 1783541011066724 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
20:03:35.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.648820 21378 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
20:03:35.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:35.650643 21569 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
20:03:35.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:35.650993 21569 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
20:03:35.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.660826 21426 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [term 1 FOLLOWER]: Refusing update from remote peer 2cb6c955523d4840b16d6f2f1d3c39f5: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:35.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.662653 21565 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [LEADER]: Connected to new peer: Peer: permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:03:35.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.713584 21557 sys_catalog.cc:455] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [sys.catalog]: SysCatalogTable state changed. Reason: New leader 2cb6c955523d4840b16d6f2f1d3c39f5. Latest consensus state: current_term: 1 leader_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:35.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.714427 21557 sys_catalog.cc:458] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [sys.catalog]: This master's current role is: FOLLOWER
20:03:35.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.736405 21557 sys_catalog.cc:455] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:35.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.737140 21557 sys_catalog.cc:458] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c [sys.catalog]: This master's current role is: FOLLOWER
20:03:35.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.743299 21565 sys_catalog.cc:455] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:35.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.744009 21565 sys_catalog.cc:458] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: This master's current role is: LEADER
20:03:35.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.745568 21571 catalog_manager.cc:1383] Generated new cluster ID: bcad7e4a9ffd4c709e9c22d59a3c8f7b
20:03:35.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.745841 21571 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
20:03:35.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.758466 21376 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } attempt: 7
20:03:35.797 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.797329 21376 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5: Bootstrap starting.
20:03:35.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.799185 21571 catalog_manager.cc:1406] Generated new certificate authority record
20:03:35.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.801235 21571 catalog_manager.cc:1540] Loading token signing keys...
20:03:35.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.817776 21376 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5: Neither blocks nor log segments found. Creating new log.
20:03:35.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.820228 21376 log.cc:826] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5: Log is configured to *not* fsync() on all Append() calls
20:03:35.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.841128 21376 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5: No bootstrap required, opened a new log
20:03:35.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.860177 21571 catalog_manager.cc:6070] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5: Generated new TSK 0
20:03:35.862 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.861791 21571 catalog_manager.cc:1550] Initializing in-progress tserver states...
20:03:35.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.875732 21296 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:35.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:35.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/wal
20:03:35.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data
20:03:35.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:35.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/logs
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data/info.pb
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:35.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.1:0
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.20.204.1
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.1
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:35.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:35.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:35.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:35.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:35.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
20:03:35.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.887621 21376 raft_consensus.cc:359] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.888509 21376 raft_consensus.cc:385] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:35.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.910852 21376 raft_consensus.cc:740] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: c8c1b381797c494d80e57ea3626eb3d5, State: Initialized, Role: FOLLOWER
20:03:35.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.912362 21376 consensus_queue.cc:260] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [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: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } }
20:03:35.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.918042 21581 sys_catalog.cc:455] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [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: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:35.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.934258 21581 sys_catalog.cc:458] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [sys.catalog]: This master's current role is: FOLLOWER
20:03:35.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.921912 21350 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "c8c1b381797c494d80e57ea3626eb3d5"
20:03:35.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.922607 21351 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "c8c1b381797c494d80e57ea3626eb3d5" is_pre_election: true
20:03:35.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.923606 21349 raft_consensus.cc:3060] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [term 0 FOLLOWER]: Advancing to term 1
20:03:35.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.921732 21376 sys_catalog.cc:565] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [sys.catalog]: configured and running, proceeding with master startup.
20:03:35.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.946578 21349 raft_consensus.cc:1275] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [term 1 FOLLOWER]: Refusing update from remote peer 2cb6c955523d4840b16d6f2f1d3c39f5: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:35.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:35.948812 21565 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [LEADER]: Connected to new peer: Peer: permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:03:36.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.023869 21581 sys_catalog.cc:455] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 2cb6c955523d4840b16d6f2f1d3c39f5. Latest consensus state: current_term: 1 leader_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:36.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.027910 21581 sys_catalog.cc:458] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [sys.catalog]: This master's current role is: FOLLOWER
20:03:36.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.025838 21590 sys_catalog.cc:455] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:36.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.029042 21590 sys_catalog.cc:458] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5 [sys.catalog]: This master's current role is: FOLLOWER
20:03:36.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.104444 21597 catalog_manager.cc:1295] Loaded cluster ID: bcad7e4a9ffd4c709e9c22d59a3c8f7b
20:03:36.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.105204 21597 catalog_manager.cc:1588] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5: loading cluster ID for follower catalog manager: success
20:03:36.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.123301 21597 catalog_manager.cc:1610] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5: acquiring CA information for follower catalog manager: success
20:03:36.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.129957 21597 catalog_manager.cc:1638] T 00000000000000000000000000000000 P c8c1b381797c494d80e57ea3626eb3d5: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
20:03:36.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:36.490983 21580 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:36.492 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:36.491838 21580 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:36.492 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:36.492547 21580 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:36.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.493538 21565 sys_catalog.cc:455] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "c8c1b381797c494d80e57ea3626eb3d5" member_type: VOTER last_known_addr { host: "127.20.204.62" port: 38711 } } peers { permanent_uuid: "660bc04b93a34962b0c6705c4518172c" member_type: VOTER last_known_addr { host: "127.20.204.61" port: 35719 } } peers { permanent_uuid: "2cb6c955523d4840b16d6f2f1d3c39f5" member_type: VOTER last_known_addr { host: "127.20.204.60" port: 37727 } } }
20:03:36.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.494479 21565 sys_catalog.cc:458] T 00000000000000000000000000000000 P 2cb6c955523d4840b16d6f2f1d3c39f5 [sys.catalog]: This master's current role is: LEADER
20:03:36.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:36.569813 21580 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:36.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:36.571201 21580 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.20.204.1
20:03:36.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.639928 21580 tablet_server_runner.cc:78] Tablet server non-default flags:
20:03:36.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:36.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:36.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/wal
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.1:0
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data/info.pb
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.1
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:36.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.20.204.1
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/logs
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:36.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
20:03:36.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
20:03:36.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 19:43:23 UTC on e7f111948823
20:03:36.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12779
20:03:36.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
20:03:36.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.641708 21580 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:36.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.644258 21580 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:03:36.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.656455 21569 catalog_manager.cc:1295] Loaded cluster ID: bcad7e4a9ffd4c709e9c22d59a3c8f7b
20:03:36.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.656754 21569 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: loading cluster ID for follower catalog manager: success
20:03:36.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.668292 21569 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: acquiring CA information for follower catalog manager: success
20:03:36.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:36.673806 21569 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 660bc04b93a34962b0c6705c4518172c: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
20:03:36.704 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:36.704214 21607 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
20:03:39.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:38.081568 21606 debug-util.cc:398] Leaking SignalData structure 0x7b0800028000 after lost signal to thread 21580
20:03:39.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:39.373378 21580 thread.cc:641] GCE (cloud detector) Time spent creating pthread: real 2.663s	user 0.922s	sys 1.570s
20:03:39.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:36.712404 21608 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
20:03:39.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:39.373735 21580 thread.cc:608] GCE (cloud detector) Time spent starting thread: real 2.663s	user 0.922s	sys 1.570s
20:03:39.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:39.381610 21612 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
20:03:39.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.381855 21580 server_base.cc:1061] running on GCE node
20:03:39.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.383369 21580 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:39.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.385989 21580 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:39.387 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.387571 21580 hybrid_clock.cc:648] HybridClock initialized: now 1783541019387523 us; error 44 us; skew 500 ppm
20:03:39.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.388441 21580 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
20:03:39.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.395076 21580 webserver.cc:533] Webserver started at http://127.20.204.1:44087/ using document root <none> and password file <none>
20:03:39.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.396196 21580 fs_manager.cc:362] Metadata directory not provided
20:03:39.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.396488 21580 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:39.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.397047 21580 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:39.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.402138 21580 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data/instance:
20:03:39.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "50e9be5168754edfaf901fd9ceca3c5f"
20:03:39.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:39 on dist-test-slave-b0qh"
20:03:39.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.403678 21580 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/wal/instance:
20:03:39.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "50e9be5168754edfaf901fd9ceca3c5f"
20:03:39.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:39 on dist-test-slave-b0qh"
20:03:39.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.412086 21580 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.004s	sys 0.004s
20:03:39.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.418110 21618 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:39.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.420543 21580 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.002s	sys 0.001s
20:03:39.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.420959 21580 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data,/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/wal
20:03:39.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "50e9be5168754edfaf901fd9ceca3c5f"
20:03:39.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:39 on dist-test-slave-b0qh"
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.421417 21580 fs_report.cc:389] FS layout report
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/wal
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/wal
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data/data
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:39.422 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:39.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.491088 21580 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:39.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.493119 21580 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:03:39.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.493690 21580 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:39.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.497102 21580 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:03:39.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.503021 21580 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:03:39.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.503307 21580 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:03:39.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.503597 21580 ts_tablet_manager.cc:616] Registered 0 tablets
20:03:39.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.503803 21580 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:03:39.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.661878 21580 rpc_server.cc:307] RPC server started. Bound to: 127.20.204.1:42959
20:03:39.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.661943 21731 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.20.204.1:42959 every 8 connection(s)
20:03:39.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.665609 21580 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/data/info.pb
20:03:39.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.674846 21296 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu as pid 21580
20:03:39.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.675669 21296 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-0/wal/instance
20:03:39.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.707686 21296 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:39.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:39.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/wal
20:03:39.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data
20:03:39.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:39.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:39.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/logs
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data/info.pb
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:39.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.2:0
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.20.204.2
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.2
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:39.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
20:03:39.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.733982 21733 heartbeater.cc:344] Connected to a master server at 127.20.204.61:35719
20:03:39.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.734541 21733 heartbeater.cc:461] Registering TS with master...
20:03:39.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.735919 21733 heartbeater.cc:507] Master 127.20.204.61:35719 requested a full tablet report, sending...
20:03:39.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.737663 21732 heartbeater.cc:344] Connected to a master server at 127.20.204.60:37727
20:03:39.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.738036 21732 heartbeater.cc:461] Registering TS with master...
20:03:39.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.739042 21732 heartbeater.cc:507] Master 127.20.204.60:37727 requested a full tablet report, sending...
20:03:39.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.739403 21416 ts_manager.cc:194] Registered new tserver with Master: 50e9be5168754edfaf901fd9ceca3c5f (127.20.204.1:42959)
20:03:39.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.742274 21734 heartbeater.cc:344] Connected to a master server at 127.20.204.62:38711
20:03:39.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.742609 21734 heartbeater.cc:461] Registering TS with master...
20:03:39.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.743156 21496 ts_manager.cc:194] Registered new tserver with Master: 50e9be5168754edfaf901fd9ceca3c5f (127.20.204.1:42959)
20:03:39.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.743512 21734 heartbeater.cc:507] Master 127.20.204.62:38711 requested a full tablet report, sending...
20:03:39.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.745980 21496 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.20.204.1:53493
20:03:39.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:39.746923 21341 ts_manager.cc:194] Registered new tserver with Master: 50e9be5168754edfaf901fd9ceca3c5f (127.20.204.1:42959)
20:03:40.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:40.050429 21742 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:40.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:40.050899 21742 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:40.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:40.051318 21742 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:40.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:40.086390 21742 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:40.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:40.087308 21742 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.20.204.2
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:40.124850 21742 tablet_server_runner.cc:78] Tablet server non-default flags:
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/wal
20:03:40.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.2:0
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data/info.pb
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.2
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:40.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.20.204.2
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/logs
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
20:03:40.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
20:03:40.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 19:43:23 UTC on e7f111948823
20:03:40.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12779
20:03:40.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
20:03:40.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:40.126309 21742 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:40.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:40.128504 21742 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:03:40.143 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:40.143384 21749 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
20:03:40.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:40.750909 21732 heartbeater.cc:499] Master 127.20.204.60:37727 was elected leader, sending a full tablet report...
20:03:41.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:40.144299 21750 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
20:03:41.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:41.402319 21742 thread.cc:641] GCE (cloud detector) Time spent creating pthread: real 1.259s	user 0.455s	sys 0.799s
20:03:41.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:41.403075 21742 thread.cc:608] GCE (cloud detector) Time spent starting thread: real 1.260s	user 0.455s	sys 0.799s
20:03:41.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.413764 21742 server_base.cc:1061] running on GCE node
20:03:41.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:41.414813 21755 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
20:03:41.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.416028 21742 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:41.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.418452 21742 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:41.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.419834 21742 hybrid_clock.cc:648] HybridClock initialized: now 1783541021419785 us; error 60 us; skew 500 ppm
20:03:41.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.420796 21742 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
20:03:41.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.427977 21742 webserver.cc:533] Webserver started at http://127.20.204.2:44927/ using document root <none> and password file <none>
20:03:41.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.429009 21742 fs_manager.cc:362] Metadata directory not provided
20:03:41.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.429278 21742 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:41.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.429839 21742 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:41.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.434492 21742 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data/instance:
20:03:41.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eb5d335a77ae44e2941cf449e19698ef"
20:03:41.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:41 on dist-test-slave-b0qh"
20:03:41.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.435722 21742 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/wal/instance:
20:03:41.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eb5d335a77ae44e2941cf449e19698ef"
20:03:41.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:41 on dist-test-slave-b0qh"
20:03:41.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.444298 21742 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.000s	sys 0.008s
20:03:41.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.450538 21760 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:41.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.451807 21742 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.004s	sys 0.000s
20:03:41.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.452188 21742 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data,/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/wal
20:03:41.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eb5d335a77ae44e2941cf449e19698ef"
20:03:41.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:41 on dist-test-slave-b0qh"
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.452580 21742 fs_report.cc:389] FS layout report
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/wal
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/wal
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data/data
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:41.453 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:41.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:41.454 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:41.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.524479 21742 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:41.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.526151 21742 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:03:41.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.526708 21742 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:41.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.529565 21742 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:03:41.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.533845 21742 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:03:41.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.534116 21742 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.001s	sys 0.000s
20:03:41.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.534435 21742 ts_tablet_manager.cc:616] Registered 0 tablets
20:03:41.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.534657 21742 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:03:41.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.675012 21742 rpc_server.cc:307] RPC server started. Bound to: 127.20.204.2:40307
20:03:41.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.675078 21872 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.20.204.2:40307 every 8 connection(s)
20:03:41.679 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.678906 21742 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/data/info.pb
20:03:41.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.681492 21296 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu as pid 21742
20:03:41.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.681964 21296 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-1/wal/instance
20:03:41.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.697570 21296 external_mini_cluster.cc:1372] Running /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:41.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu
20:03:41.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/wal
20:03:41.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/logs
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data/info.pb
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
20:03:41.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
20:03:41.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:41.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:41.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:41.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
20:03:41.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
20:03:41.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.3:0
20:03:41.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.20.204.3
20:03:41.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.3
20:03:41.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:41.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:41.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:41.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:41.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:41.701 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
20:03:41.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.730638 21873 heartbeater.cc:344] Connected to a master server at 127.20.204.60:37727
20:03:41.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.731117 21873 heartbeater.cc:461] Registering TS with master...
20:03:41.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.732568 21873 heartbeater.cc:507] Master 127.20.204.60:37727 requested a full tablet report, sending...
20:03:41.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.733691 21875 heartbeater.cc:344] Connected to a master server at 127.20.204.62:38711
20:03:41.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.734319 21875 heartbeater.cc:461] Registering TS with master...
20:03:41.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.735190 21875 heartbeater.cc:507] Master 127.20.204.62:38711 requested a full tablet report, sending...
20:03:41.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.735491 21496 ts_manager.cc:194] Registered new tserver with Master: eb5d335a77ae44e2941cf449e19698ef (127.20.204.2:40307)
20:03:41.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.736979 21496 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.20.204.2:49293
20:03:41.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.738349 21341 ts_manager.cc:194] Registered new tserver with Master: eb5d335a77ae44e2941cf449e19698ef (127.20.204.2:40307)
20:03:41.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.738617 21874 heartbeater.cc:344] Connected to a master server at 127.20.204.61:35719
20:03:41.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.738958 21874 heartbeater.cc:461] Registering TS with master...
20:03:41.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.740451 21874 heartbeater.cc:507] Master 127.20.204.61:35719 requested a full tablet report, sending...
20:03:41.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:41.743055 21416 ts_manager.cc:194] Registered new tserver with Master: eb5d335a77ae44e2941cf449e19698ef (127.20.204.2:40307)
20:03:42.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:42.029846 21883 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
20:03:42.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:42.030470 21883 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
20:03:42.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:42.031066 21883 flags.cc:432] Enabled unsafe flag: --never_fsync=true
20:03:42.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:42.064644 21883 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
20:03:42.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:42.065704 21883 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.20.204.3
20:03:42.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:42.103051 21883 tablet_server_runner.cc:78] Tablet server non-default flags:
20:03:42.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
20:03:42.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.20.204.20:38575
20:03:42.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
20:03:42.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
20:03:42.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data
20:03:42.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/wal
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.20.204.3:0
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data/info.pb
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.20.204.3
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.20.204.62:38711,127.20.204.61:35719,127.20.204.60:37727
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.20.204.3
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/logs
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
20:03:42.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 844bedc15e81bd62879377e001142786f7d931d8
20:03:42.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
20:03:42.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 19:43:23 UTC on e7f111948823
20:03:42.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 12779
20:03:42.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
20:03:42.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:42.104784 21883 env_posix.cc:2285] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
20:03:42.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:42.106668 21883 file_cache.cc:504] Constructed file cache file cache with capacity 419430
20:03:42.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:42.120044 21889 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
20:03:42.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:42.741439 21873 heartbeater.cc:499] Master 127.20.204.60:37727 was elected leader, sending a full tablet report...
20:03:43.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:42.120863 21890 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
20:03:43.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:43.431761 21892 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
20:03:43.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:43.434938 21891 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1309 milliseconds
20:03:43.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.435014 21883 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
20:03:43.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.436296 21883 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
20:03:43.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.438608 21883 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
20:03:43.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.440016 21883 hybrid_clock.cc:648] HybridClock initialized: now 1783541023439968 us; error 57 us; skew 500 ppm
20:03:43.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.440883 21883 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
20:03:43.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.448956 21883 webserver.cc:533] Webserver started at http://127.20.204.3:43659/ using document root <none> and password file <none>
20:03:43.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.449968 21883 fs_manager.cc:362] Metadata directory not provided
20:03:43.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.450284 21883 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
20:03:43.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.450883 21883 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
20:03:43.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.455147 21883 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data/instance:
20:03:43.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a0b1ac9484034ce3b7f178ac63fa2a08"
20:03:43.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:43 on dist-test-slave-b0qh"
20:03:43.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.456315 21883 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/wal/instance:
20:03:43.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a0b1ac9484034ce3b7f178ac63fa2a08"
20:03:43.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:43 on dist-test-slave-b0qh"
20:03:43.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.464784 21883 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.009s	sys 0.000s
20:03:43.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.471205 21900 log_block_manager.cc:4127] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
20:03:43.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.472254 21883 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.000s
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.472635 21883 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data,/tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/wal
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "a0b1ac9484034ce3b7f178ac63fa2a08"
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 20:03:43 on dist-test-slave-b0qh"
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.473011 21883 fs_report.cc:389] FS layout report
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/wal
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/wal
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data/data
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
20:03:43.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
20:03:43.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
20:03:43.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
20:03:43.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
20:03:43.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
20:03:43.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
20:03:43.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
20:03:43.474 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
20:03:43.530 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.529942 21883 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
20:03:43.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.531678 21883 env_posix.cc:2285] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
20:03:43.532 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.532220 21883 kserver.cc:163] Server-wide thread pool size limit: 3276
20:03:43.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.534895 21883 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
20:03:43.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.539716 21883 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
20:03:43.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.539960 21883 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
20:03:43.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.540295 21883 ts_tablet_manager.cc:616] Registered 0 tablets
20:03:43.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.540490 21883 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
20:03:43.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.688776 21883 rpc_server.cc:307] RPC server started. Bound to: 127.20.204.3:43335
20:03:43.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.689307 22012 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.20.204.3:43335 every 8 connection(s)
20:03:43.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.691798 21883 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/data/info.pb
20:03:43.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.699862 21296 external_mini_cluster.cc:1434] Started /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu as pid 21883
20:03:43.702 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.700454 21296 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taskP3hnwj/test-tmp/mini-kudu-cluster15240572940123549767/ts-2/wal/instance
20:03:43.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.740548 22014 heartbeater.cc:344] Connected to a master server at 127.20.204.61:35719
20:03:43.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.741065 22014 heartbeater.cc:461] Registering TS with master...
20:03:43.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.742442 22014 heartbeater.cc:507] Master 127.20.204.61:35719 requested a full tablet report, sending...
20:03:43.742 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.742755 22013 heartbeater.cc:344] Connected to a master server at 127.20.204.60:37727
20:03:43.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.743065 22013 heartbeater.cc:461] Registering TS with master...
20:03:43.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.743958 22013 heartbeater.cc:507] Master 127.20.204.60:37727 requested a full tablet report, sending...
20:03:43.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.746284 21416 ts_manager.cc:194] Registered new tserver with Master: a0b1ac9484034ce3b7f178ac63fa2a08 (127.20.204.3:43335)
20:03:43.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.747052 21495 ts_manager.cc:194] Registered new tserver with Master: a0b1ac9484034ce3b7f178ac63fa2a08 (127.20.204.3:43335)
20:03:43.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.748225 22015 heartbeater.cc:344] Connected to a master server at 127.20.204.62:38711
20:03:43.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.748595 22015 heartbeater.cc:461] Registering TS with master...
20:03:43.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.749157 21495 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.20.204.3:41973
20:03:43.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.749496 22015 heartbeater.cc:507] Master 127.20.204.62:38711 requested a full tablet report, sending...
20:03:43.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.754253 21341 ts_manager.cc:194] Registered new tserver with Master: a0b1ac9484034ce3b7f178ac63fa2a08 (127.20.204.3:43335)
20:03:43.771 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:43.770984 21296 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
20:03:43.783 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
20:03:43.784 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

20:03:43.793 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.20.204.62"
      port: 38711
    }
    bound_http_address {
      host: "127.20.204.62"
      port: 42111
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.20.204.61"
      port: 35719
    }
    bound_http_address {
      host: "127.20.204.61"
      port: 35163
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.20.204.60"
      port: 37727
    }
    bound_http_address {
      host: "127.20.204.60"
      port: 38067
    }
  }
}

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

20:03:43.799 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.20.204.1"
      port: 42959
    }
    bound_http_address {
      host: "127.20.204.1"
      port: 44087
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.20.204.2"
      port: 40307
    }
    bound_http_address {
      host: "127.20.204.2"
      port: 44927
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.20.204.3"
      port: 43335
    }
    bound_http_address {
      host: "127.20.204.3"
      port: 43659
    }
  }
}

20:03:43.799 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
20:03:43.979 [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)
20:03:44.037 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
20:03:44.221 [DEBUG - main] (Connection.java:748) [peer master-127.20.204.60:37727(127.20.204.60:37727)] connecting to peer
20:03:44.279 [DEBUG - main] (Connection.java:748) [peer master-127.20.204.62:38711(127.20.204.62:38711)] connecting to peer
20:03:44.280 [DEBUG - main] (Connection.java:748) [peer master-127.20.204.61:35719(127.20.204.61:35719)] connecting to peer
20:03:44.303 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.20.204.61:35719(127.20.204.61:35719)] Successfully connected to peer
20:03:44.303 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.20.204.60:37727(127.20.204.60:37727)] Successfully connected to peer
20:03:44.314 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.20.204.62:38711(127.20.204.62:38711)] Successfully connected to peer
20:03:44.413 [DEBUG - kudu-nio-1] (Negotiator.java:528) SASL mechanism PLAIN chosen for peer 127.20.204.62
20:03:44.413 [DEBUG - kudu-nio-2] (Negotiator.java:528) SASL mechanism PLAIN chosen for peer 127.20.204.61
20:03:44.413 [DEBUG - kudu-nio-0] (Negotiator.java:528) SASL mechanism PLAIN chosen for peer 127.20.204.60
20:03:44.642 [DEBUG - kudu-nio-0] (Negotiator.java:918) Authenticated connection [id: 0x94a27456, L:/127.0.0.1:42750 - R:/127.20.204.60:37727] using SASL/PLAIN
20:03:44.642 [DEBUG - kudu-nio-1] (Negotiator.java:918) Authenticated connection [id: 0x0f392eb8, L:/127.0.0.1:52346 - R:/127.20.204.62:38711] using SASL/PLAIN
20:03:44.642 [DEBUG - kudu-nio-2] (Negotiator.java:918) Authenticated connection [id: 0xb1d32de0, L:/127.0.0.1:48810 - R:/127.20.204.61:35719] using SASL/PLAIN
20:03:44.681 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
20:03:44.682 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=507), Traces: [0ms] refreshing cache from master, [28ms] Sub RPC ConnectToMaster: sending RPC to server master-127.20.204.60:37727, [99ms] Sub RPC ConnectToMaster: sending RPC to server master-127.20.204.62:38711, [100ms] Sub RPC ConnectToMaster: sending RPC to server master-127.20.204.61:35719, [476ms] Sub RPC ConnectToMaster: received response from server master-127.20.204.62:38711: OK, [476ms] Sub RPC ConnectToMaster: received response from server master-127.20.204.61:35719: OK, [476ms] Sub RPC ConnectToMaster: received response from server master-127.20.204.60:37727: OK, deferred=null) after lookup
20:03:44.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.730458 21495 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:42750:
20:03:44.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1783541008483"
20:03:44.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
20:03:44.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
20:03:44.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
20:03:44.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
20:03:44.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
20:03:44.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
20:03:44.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
20:03:44.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
20:03:44.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:44.733633 21495 catalog_manager.cc:7077] 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-1783541008483 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
20:03:44.761 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.755940 22013 heartbeater.cc:499] Master 127.20.204.60:37727 was elected leader, sending a full tablet report...
20:03:44.804 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table f7ab8e86d07246d0965670651a8e4964
20:03:44.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.823071 21948 tablet_service.cc:1511] Processing CreateTablet for tablet 9674d4bed39d40e194efe120d3eefbdd (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783541008483 [id=f7ab8e86d07246d0965670651a8e4964]), partition=RANGE (key) PARTITION UNBOUNDED
20:03:44.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.825453 21948 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9674d4bed39d40e194efe120d3eefbdd. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:44.862 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.862227 22046 tablet_bootstrap.cc:492] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08: Bootstrap starting.
20:03:44.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.867897 21808 tablet_service.cc:1511] Processing CreateTablet for tablet 9674d4bed39d40e194efe120d3eefbdd (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783541008483 [id=f7ab8e86d07246d0965670651a8e4964]), partition=RANGE (key) PARTITION UNBOUNDED
20:03:44.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.867890 21667 tablet_service.cc:1511] Processing CreateTablet for tablet 9674d4bed39d40e194efe120d3eefbdd (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783541008483 [id=f7ab8e86d07246d0965670651a8e4964]), partition=RANGE (key) PARTITION UNBOUNDED
20:03:44.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.869266 22046 tablet_bootstrap.cc:654] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08: Neither blocks nor log segments found. Creating new log.
20:03:44.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.869756 21667 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9674d4bed39d40e194efe120d3eefbdd. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:44.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.869756 21808 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 9674d4bed39d40e194efe120d3eefbdd. 1 dirs total, 0 dirs full, 0 dirs failed
20:03:44.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.871569 22046 log.cc:826] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08: Log is configured to *not* fsync() on all Append() calls
20:03:44.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.877063 22046 tablet_bootstrap.cc:492] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08: No bootstrap required, opened a new log
20:03:44.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.877925 22046 ts_tablet_manager.cc:1403] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08: Time spent bootstrapping tablet: real 0.017s	user 0.003s	sys 0.012s
20:03:44.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.899096 22048 tablet_bootstrap.cc:492] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f: Bootstrap starting.
20:03:44.906 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.905707 22049 tablet_bootstrap.cc:492] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef: Bootstrap starting.
20:03:44.908 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.907773 22048 tablet_bootstrap.cc:654] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f: Neither blocks nor log segments found. Creating new log.
20:03:44.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.908324 22046 raft_consensus.cc:359] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:44.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.909230 22046 raft_consensus.cc:385] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:44.909 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.909570 22046 raft_consensus.cc:740] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: a0b1ac9484034ce3b7f178ac63fa2a08, State: Initialized, Role: FOLLOWER
20:03:44.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.910257 22048 log.cc:826] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f: Log is configured to *not* fsync() on all Append() calls
20:03:44.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.910602 22046 consensus_queue.cc:260] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [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: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:44.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.914470 22049 tablet_bootstrap.cc:654] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef: Neither blocks nor log segments found. Creating new log.
20:03:44.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.918033 22046 ts_tablet_manager.cc:1434] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08: Time spent starting tablet: real 0.040s	user 0.029s	sys 0.009s
20:03:44.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.918753 22048 tablet_bootstrap.cc:492] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f: No bootstrap required, opened a new log
20:03:44.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.919269 22048 ts_tablet_manager.cc:1403] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f: Time spent bootstrapping tablet: real 0.022s	user 0.009s	sys 0.009s
20:03:44.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.922672 22049 log.cc:826] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef: Log is configured to *not* fsync() on all Append() calls
20:03:44.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.929952 22049 tablet_bootstrap.cc:492] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef: No bootstrap required, opened a new log
20:03:44.931 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.930859 22049 ts_tablet_manager.cc:1403] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef: Time spent bootstrapping tablet: real 0.026s	user 0.019s	sys 0.000s
20:03:44.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.947211 22048 raft_consensus.cc:359] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:44.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.947947 22048 raft_consensus.cc:385] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:44.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.948228 22048 raft_consensus.cc:740] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 50e9be5168754edfaf901fd9ceca3c5f, State: Initialized, Role: FOLLOWER
20:03:44.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.949201 22048 consensus_queue.cc:260] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [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: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:44.953 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.953379 22048 ts_tablet_manager.cc:1434] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f: Time spent starting tablet: real 0.034s	user 0.032s	sys 0.000s
20:03:44.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.953660 22049 raft_consensus.cc:359] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:44.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.954744 22049 raft_consensus.cc:385] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
20:03:44.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.955215 22049 raft_consensus.cc:740] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: eb5d335a77ae44e2941cf449e19698ef, State: Initialized, Role: FOLLOWER
20:03:44.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.956348 22049 consensus_queue.cc:260] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [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: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:44.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:44.967480 21739 tablet.cc:2406] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:44.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:44.969764 22049 ts_tablet_manager.cc:1434] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef: Time spent starting tablet: real 0.038s	user 0.025s	sys 0.008s
20:03:44.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:44.981220 22021 tablet.cc:2406] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:45.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 20:03:45.202666 21878 tablet.cc:2406] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef: Can't schedule compaction. Clean time has not been advanced past its initial value.
20:03:45.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.225011 22054 raft_consensus.cc:493] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
20:03:45.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.225577 22054 raft_consensus.cc:515] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:45.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.228328 22054 leader_election.cc:290] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers a0b1ac9484034ce3b7f178ac63fa2a08 (127.20.204.3:43335), 50e9be5168754edfaf901fd9ceca3c5f (127.20.204.1:42959)
20:03:45.241 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.241341 21968 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9674d4bed39d40e194efe120d3eefbdd" candidate_uuid: "eb5d335a77ae44e2941cf449e19698ef" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" is_pre_election: true
20:03:45.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.241346 21687 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9674d4bed39d40e194efe120d3eefbdd" candidate_uuid: "eb5d335a77ae44e2941cf449e19698ef" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "50e9be5168754edfaf901fd9ceca3c5f" is_pre_election: true
20:03:45.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.242017 21968 raft_consensus.cc:2468] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate eb5d335a77ae44e2941cf449e19698ef in term 0.
20:03:45.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.242091 21687 raft_consensus.cc:2468] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate eb5d335a77ae44e2941cf449e19698ef in term 0.
20:03:45.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.243981 21761 leader_election.cc:304] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [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: a0b1ac9484034ce3b7f178ac63fa2a08, eb5d335a77ae44e2941cf449e19698ef; no voters: 
20:03:45.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.244747 22054 raft_consensus.cc:2804] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Leader pre-election won for term 1
20:03:45.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.245105 22054 raft_consensus.cc:493] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
20:03:45.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.245347 22054 raft_consensus.cc:3060] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 0 FOLLOWER]: Advancing to term 1
20:03:45.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.250109 22054 raft_consensus.cc:515] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:45.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.251585 22054 leader_election.cc:290] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [CANDIDATE]: Term 1 election: Requested vote from peers a0b1ac9484034ce3b7f178ac63fa2a08 (127.20.204.3:43335), 50e9be5168754edfaf901fd9ceca3c5f (127.20.204.1:42959)
20:03:45.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.252480 21968 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9674d4bed39d40e194efe120d3eefbdd" candidate_uuid: "eb5d335a77ae44e2941cf449e19698ef" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08"
20:03:45.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.252532 21687 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "9674d4bed39d40e194efe120d3eefbdd" candidate_uuid: "eb5d335a77ae44e2941cf449e19698ef" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "50e9be5168754edfaf901fd9ceca3c5f"
20:03:45.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.252912 21687 raft_consensus.cc:3060] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [term 0 FOLLOWER]: Advancing to term 1
20:03:45.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.252941 21968 raft_consensus.cc:3060] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [term 0 FOLLOWER]: Advancing to term 1
20:03:45.257 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.257417 21687 raft_consensus.cc:2468] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate eb5d335a77ae44e2941cf449e19698ef in term 1.
20:03:45.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.257761 21968 raft_consensus.cc:2468] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate eb5d335a77ae44e2941cf449e19698ef in term 1.
20:03:45.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.258512 21763 leader_election.cc:304] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [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: 50e9be5168754edfaf901fd9ceca3c5f, eb5d335a77ae44e2941cf449e19698ef; no voters: 
20:03:45.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.259511 22054 raft_consensus.cc:2804] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 1 FOLLOWER]: Leader election won for term 1
20:03:45.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.261008 22054 raft_consensus.cc:697] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [term 1 LEADER]: Becoming Leader. State: Replica: eb5d335a77ae44e2941cf449e19698ef, State: Running, Role: LEADER
20:03:45.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.261895 22054 consensus_queue.cc:237] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [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: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } }
20:03:45.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.273427 21495 catalog_manager.cc:5697] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef reported cstate change: term changed from 0 to 1, leader changed from <none> to eb5d335a77ae44e2941cf449e19698ef (127.20.204.2). New cstate: current_term: 1 leader_uuid: "eb5d335a77ae44e2941cf449e19698ef" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "eb5d335a77ae44e2941cf449e19698ef" member_type: VOTER last_known_addr { host: "127.20.204.2" port: 40307 } health_report { overall_health: HEALTHY } } }
20:03:45.379 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet 9674d4bed39d40e194efe120d3eefbdd for table 'org.apache.kudu.client.TestHybridTime-1783541008483' with partition [<start>, <end>)
20:03:45.385 [DEBUG - kudu-nio-0] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=9674d4bed39d40e194efe120d3eefbdd@[50e9be5168754edfaf901fd9ceca3c5f(127.20.204.1:42959),a0b1ac9484034ce3b7f178ac63fa2a08(127.20.204.3:43335),eb5d335a77ae44e2941cf449e19698ef(127.20.204.2:40307)[L]]}]
20:03:45.386 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=17), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.20.204.60:37727, [8ms] Sub RPC GetTableLocations: received response from server master-127.20.204.60:37727: OK, deferred=null) after lookup
20:03:45.395 [DEBUG - kudu-nio-0] (Connection.java:748) [peer eb5d335a77ae44e2941cf449e19698ef(127.20.204.2:40307)] connecting to peer
20:03:45.398 [DEBUG - kudu-nio-3] (Connection.java:757) [peer eb5d335a77ae44e2941cf449e19698ef(127.20.204.2:40307)] Successfully connected to peer
20:03:45.474 [DEBUG - kudu-nio-3] (Negotiator.java:918) Authenticated connection [id: 0x0a4092be, L:/127.0.0.1:59526 - R:/127.20.204.2:40307] using TOKEN/null
20:03:45.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.488318 21968 raft_consensus.cc:1275] T 9674d4bed39d40e194efe120d3eefbdd P a0b1ac9484034ce3b7f178ac63fa2a08 [term 1 FOLLOWER]: Refusing update from remote peer eb5d335a77ae44e2941cf449e19698ef: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:45.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.488396 21687 raft_consensus.cc:1275] T 9674d4bed39d40e194efe120d3eefbdd P 50e9be5168754edfaf901fd9ceca3c5f [term 1 FOLLOWER]: Refusing update from remote peer eb5d335a77ae44e2941cf449e19698ef: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
20:03:45.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.490101 22057 consensus_queue.cc:1048] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [LEADER]: Connected to new peer: Peer: permanent_uuid: "a0b1ac9484034ce3b7f178ac63fa2a08" member_type: VOTER last_known_addr { host: "127.20.204.3" port: 43335 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.001s
20:03:45.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.490790 22054 consensus_queue.cc:1048] T 9674d4bed39d40e194efe120d3eefbdd P eb5d335a77ae44e2941cf449e19698ef [LEADER]: Connected to new peer: Peer: permanent_uuid: "50e9be5168754edfaf901fd9ceca3c5f" member_type: VOTER last_known_addr { host: "127.20.204.1" port: 42959 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
20:03:45.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.519683 22064 mvcc.cc:204] Tried to move back new op lower bound from 7305384040381444096 to 7305384039481163776. Current Snapshot: MvccSnapshot[applied={T|T < 7305384040381444096}]
20:03:45.626 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:45.653 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:45.687 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:45.693 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1421051367(state=PENDING, result=null, callback=<none>, errback=<none>)}
20:03:45.724 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:45.724 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1443988081(state=PENDING, result=null, callback=<none>, errback=<none>)}
20:03:45.756 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:45.756 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@647699694(state=PENDING, result=null, callback=<none>, errback=<none>)}
20:03:45.787 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
20:03:45.850 [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-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.864 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7305384060861444097) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.874 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7305384060861444101) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.883 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7305384060861444105) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.892 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7305384060861444109) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.901 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7305384060861444117) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.912 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7305384060861444122) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.921 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7305384060861444123) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783541008483, tablet=9674d4bed39d40e194efe120d3eefbdd, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
20:03:45.924 [DEBUG - main] (Connection.java:575) [peer master-127.20.204.62:38711(127.20.204.62:38711)] disconnecting while in state READY
20:03:45.925 [DEBUG - main] (Connection.java:575) [peer eb5d335a77ae44e2941cf449e19698ef(127.20.204.2:40307)] disconnecting while in state READY
20:03:45.925 [DEBUG - main] (Connection.java:575) [peer master-127.20.204.61:35719(127.20.204.61:35719)] disconnecting while in state READY
20:03:45.926 [DEBUG - main] (Connection.java:575) [peer master-127.20.204.60:37727(127.20.204.60:37727)] disconnecting while in state READY
20:03:45.930 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.20.204.60:37727(127.20.204.60:37727)] handling channelInactive
20:03:45.931 [DEBUG - kudu-nio-3] (Connection.java:239) [peer eb5d335a77ae44e2941cf449e19698ef(127.20.204.2:40307)] handling channelInactive
20:03:45.931 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2704) Releasing all remaining resources
20:03:45.931 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.20.204.60:37727(127.20.204.60:37727)] cleaning up while in state READY due to: connection closed
20:03:45.931 [DEBUG - kudu-nio-3] (Connection.java:709) [peer eb5d335a77ae44e2941cf449e19698ef(127.20.204.2:40307)] cleaning up while in state READY due to: connection closed
20:03:45.930 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.20.204.61:35719(127.20.204.61:35719)] handling channelInactive
20:03:45.932 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.20.204.61:35719(127.20.204.61:35719)] cleaning up while in state READY due to: connection closed
20:03:45.938 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.20.204.62:38711(127.20.204.62:38711)] handling channelInactive
20:03:45.938 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.20.204.62:38711(127.20.204.62:38711)] cleaning up while in state READY due to: connection closed
20:03:45.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.939517 21296 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu with pid 21580
20:03:45.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:45.976768 21296 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu with pid 21742
20:03:46.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:46.010478 21296 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu with pid 21883
20:03:46.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:46.039808 21296 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu with pid 21308
20:03:46.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:46.077770 21296 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu with pid 21378
20:03:46.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 20:03:46.111724 21296 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taskP3hnwj/build/tsan/bin/kudu with pid 21453
20:03:47.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T20:03:47Z chronyd exiting

Time: 19.973

OK (1 test)

