JUnit version 4.13.2
.12:12:10.662 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1783512730652
12:12:10.686 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
12:12:10.723 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
12:12:10.731 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taska0vbhI/test-tmp
12:12:10.756 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
12:12:10.760 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taska0vbhI/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
12:12:10.952 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

12:12:11.490 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
12:12:11.491 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

12:12:11.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.497550 25786 mini_oidc.cc:150] Starting JWKS server
12:12:11.503 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.502760 25786 webserver.cc:533] Webserver started at http://127.0.0.1:46413/ using document root <none> and password file <none>
12:12:11.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.503367 25786 mini_oidc.cc:186] Starting OIDC Discovery server
12:12:11.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.505609 25786 webserver.cc:533] Webserver started at http://127.0.0.1:34135/ using document root <none> and password file <none>
12:12:11.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T12:12:11Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
12:12:11.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T12:12:11Z Disabled control of system clock
12:12:11.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.574448 25786 external_mini_cluster.cc:1372] Running /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:11.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:11.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/wal
12:12:11.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data
12:12:11.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
12:12:11.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
12:12:11.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
12:12:11.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:11.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:11.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:11.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/logs
12:12:11.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data/info.pb
12:12:11.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:11.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
12:12:11.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
12:12:11.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
12:12:11.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
12:12:11.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.190:43805
12:12:11.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.190
12:12:11.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:11.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:11.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:11.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:11.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:11.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
12:12:11.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:11.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
12:12:11.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46413/jwks.json with env {}
12:12:11.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.887588 25798 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
12:12:11.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.888156 25798 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
12:12:11.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.888622 25798 flags.cc:432] Enabled unsafe flag: --never_fsync=true
12:12:11.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.921494 25798 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
12:12:11.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.921797 25798 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
12:12:11.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.922003 25798 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
12:12:11.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.922190 25798 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
12:12:11.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.922369 25798 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
12:12:11.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.922506 25798 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:46413/jwks.json
12:12:11.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.968084 25798 master_runner.cc:387] Master server non-default flags:
12:12:11.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:11.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:11.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:11.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/wal
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.190:43805
12:12:11.970 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
12:12:11.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
12:12:11.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
12:12:11.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46413/jwks.json
12:12:11.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:11.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:11.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data/info.pb
12:12:11.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.190
12:12:11.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:11.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
12:12:11.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:11.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
12:12:11.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
12:12:11.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:11.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/logs
12:12:11.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:11.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
12:12:11.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
12:12:11.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
12:12:11.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
12:12:11.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 8aadabe0236da0a312e7e3764fada9dc81e70c40
12:12:11.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
12:12:11.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 11:49:08 UTC on e7f111948823
12:12:11.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16857
12:12:11.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
12:12:11.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.969972 25798 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
12:12:11.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:11.972380 25798 file_cache.cc:492] Constructed file cache file cache with capacity 419430
12:12:11.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.986287 25804 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
12:12:13.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.390240 25803 debug-util.cc:398] Leaking SignalData structure 0x7b0800028020 after lost signal to thread 25798
12:12:13.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.423441 25798 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.437s	user 0.573s	sys 0.864s
12:12:13.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.423789 25798 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.437s	user 0.573s	sys 0.864s
12:12:13.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:11.987759 25805 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
12:12:13.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.424355 25806 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
12:12:13.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.425976 25807 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
12:12:13.426 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.425954 25798 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
12:12:13.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.433239 25798 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
12:12:13.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.435855 25798 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
12:12:13.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.437216 25798 hybrid_clock.cc:648] HybridClock initialized: now 1783512733437186 us; error 69 us; skew 500 ppm
12:12:13.438 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.438179 25798 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
12:12:13.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.444289 25798 webserver.cc:533] Webserver started at http://127.25.46.190:32945/ using document root <none> and password file <none>
12:12:13.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.445291 25798 fs_manager.cc:362] Metadata directory not provided
12:12:13.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.445485 25798 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
12:12:13.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.445878 25798 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
12:12:13.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.450155 25798 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data/instance:
12:12:13.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cb895826d0f34856aa35036ceedf660e"
12:12:13.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:13 on dist-test-slave-zz1r"
12:12:13.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.451479 25798 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/wal/instance:
12:12:13.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cb895826d0f34856aa35036ceedf660e"
12:12:13.452 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:13 on dist-test-slave-zz1r"
12:12:13.459 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.459152 25798 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.002s
12:12:13.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.465097 25814 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
12:12:13.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.466359 25798 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.001s
12:12:13.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.466821 25798 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data,/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/wal
12:12:13.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cb895826d0f34856aa35036ceedf660e"
12:12:13.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:13 on dist-test-slave-zz1r"
12:12:13.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.467329 25798 fs_report.cc:389] FS layout report
12:12:13.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
12:12:13.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/wal
12:12:13.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/wal
12:12:13.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data/data
12:12:13.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
12:12:13.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
12:12:13.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
12:12:13.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
12:12:13.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
12:12:13.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
12:12:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
12:12:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
12:12:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
12:12:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
12:12:13.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
12:12:13.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.524003 25798 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
12:12:13.525 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.525501 25798 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
12:12:13.526 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.525923 25798 kserver.cc:163] Server-wide thread pool size limit: 3276
12:12:13.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.606284 25798 rpc_server.cc:307] RPC server started. Bound to: 127.25.46.190:43805
12:12:13.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.606401 25865 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.46.190:43805 every 8 connection(s)
12:12:13.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.609634 25798 server_base.cc:1193] Dumped server information to /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/data/info.pb
12:12:13.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.616127 25866 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
12:12:13.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.618165 25786 external_mini_cluster.cc:1434] Started /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu as pid 25798
12:12:13.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.618551 25786 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-0/wal/instance
12:12:13.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.622062 25786 external_mini_cluster.cc:1372] Running /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:13.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:13.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/wal
12:12:13.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data
12:12:13.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
12:12:13.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
12:12:13.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
12:12:13.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:13.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:13.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:13.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/logs
12:12:13.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data/info.pb
12:12:13.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:13.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
12:12:13.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
12:12:13.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
12:12:13.626 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
12:12:13.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:13.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:13.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:13.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
12:12:13.627 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
12:12:13.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
12:12:13.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
12:12:13.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.189:45923
12:12:13.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.189
12:12:13.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:13.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:13.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:13.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:13.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:13.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
12:12:13.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:13.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
12:12:13.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46413/jwks.json with env {}
12:12:13.633 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.630825 25866 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } has no permanent_uuid. Determining permanent_uuid...
12:12:13.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.662575 25866 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } has no permanent_uuid. Determining permanent_uuid...
12:12:13.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.665302 25816 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:13.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.669960 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.189:45923: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:13.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.719456 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } attempt: 1
12:12:13.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.725303 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.189:45923: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:13.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.793727 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } attempt: 2
12:12:13.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.798576 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.189:45923: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:13.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:13.890134 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } attempt: 3
12:12:13.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.895046 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.189:45923: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:13.954 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.953847 25868 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
12:12:13.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.954572 25868 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
12:12:13.955 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.955392 25868 flags.cc:432] Enabled unsafe flag: --never_fsync=true
12:12:13.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.989877 25868 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
12:12:13.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.990284 25868 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
12:12:13.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.990619 25868 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
12:12:13.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.990991 25868 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
12:12:13.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.991372 25868 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
12:12:13.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:13.991648 25868 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:46413/jwks.json
12:12:14.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:14.032685 25868 master_runner.cc:387] Master server non-default flags:
12:12:14.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:14.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:14.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:14.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:14.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data
12:12:14.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/wal
12:12:14.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
12:12:14.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:14.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:14.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:14.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
12:12:14.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.189:45923
12:12:14.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
12:12:14.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
12:12:14.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
12:12:14.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46413/jwks.json
12:12:14.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:14.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:14.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data/info.pb
12:12:14.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.189
12:12:14.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:14.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
12:12:14.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:14.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
12:12:14.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
12:12:14.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:14.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/logs
12:12:14.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:14.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
12:12:14.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
12:12:14.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
12:12:14.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
12:12:14.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 8aadabe0236da0a312e7e3764fada9dc81e70c40
12:12:14.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
12:12:14.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 11:49:08 UTC on e7f111948823
12:12:14.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16857
12:12:14.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
12:12:14.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:14.034557 25868 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
12:12:14.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:14.037060 25868 file_cache.cc:492] Constructed file cache file cache with capacity 419430
12:12:14.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:14.038481 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } attempt: 4
12:12:14.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:14.044006 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.189:45923: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:14.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:14.052074 25877 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
12:12:14.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:14.343567 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } attempt: 5
12:12:14.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:14.352663 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.189:45923: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:14.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:14.900276 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } attempt: 6
12:12:14.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:14.931345 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.189:45923: Network error: Client connection negotiation failed: client connection to 127.25.46.189:45923: connect: Connection refused (error 111)
12:12:15.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:14.052606 25878 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
12:12:15.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.371084 25879 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1318 milliseconds
12:12:15.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.373234 25880 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
12:12:15.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.385425 25868 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.334s	user 0.437s	sys 0.869s
12:12:15.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.385828 25868 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.334s	user 0.437s	sys 0.869s
12:12:15.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.386123 25868 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
12:12:15.388 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.387616 25868 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
12:12:15.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.390861 25868 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
12:12:15.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.392409 25868 hybrid_clock.cc:648] HybridClock initialized: now 1783512735392349 us; error 65 us; skew 500 ppm
12:12:15.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.393707 25868 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
12:12:15.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.402351 25868 webserver.cc:533] Webserver started at http://127.25.46.189:36829/ using document root <none> and password file <none>
12:12:15.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.403576 25868 fs_manager.cc:362] Metadata directory not provided
12:12:15.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.403959 25868 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
12:12:15.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.404560 25868 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
12:12:15.409 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.409263 25868 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data/instance:
12:12:15.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d9a0de64ccd447018ab1bd3cc05b94ab"
12:12:15.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:15 on dist-test-slave-zz1r"
12:12:15.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.410784 25868 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/wal/instance:
12:12:15.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d9a0de64ccd447018ab1bd3cc05b94ab"
12:12:15.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:15 on dist-test-slave-zz1r"
12:12:15.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.420754 25868 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.004s	sys 0.003s
12:12:15.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.427958 25889 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
12:12:15.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.429435 25868 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.001s	sys 0.004s
12:12:15.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.429840 25868 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data,/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/wal
12:12:15.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d9a0de64ccd447018ab1bd3cc05b94ab"
12:12:15.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:15 on dist-test-slave-zz1r"
12:12:15.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.430282 25868 fs_report.cc:389] FS layout report
12:12:15.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
12:12:15.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/wal
12:12:15.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/wal
12:12:15.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data/data
12:12:15.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
12:12:15.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
12:12:15.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
12:12:15.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
12:12:15.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
12:12:15.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
12:12:15.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
12:12:15.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
12:12:15.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
12:12:15.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
12:12:15.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
12:12:15.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.517458 25868 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
12:12:15.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.519446 25868 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
12:12:15.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.519873 25868 kserver.cc:163] Server-wide thread pool size limit: 3276
12:12:15.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.593987 25868 rpc_server.cc:307] RPC server started. Bound to: 127.25.46.189:45923
12:12:15.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.594143 25940 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.46.189:45923 every 8 connection(s)
12:12:15.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.597098 25868 server_base.cc:1193] Dumped server information to /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/data/info.pb
12:12:15.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.602931 25786 external_mini_cluster.cc:1434] Started /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu as pid 25868
12:12:15.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.603451 25786 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-1/wal/instance
12:12:15.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.604640 25941 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
12:12:15.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.606444 25786 external_mini_cluster.cc:1372] Running /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:15.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:15.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/wal
12:12:15.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data
12:12:15.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
12:12:15.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
12:12:15.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/logs
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data/info.pb
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
12:12:15.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
12:12:15.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.188:38273
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.188
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:15.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
12:12:15.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:15.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
12:12:15.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46413/jwks.json with env {}
12:12:15.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.617847 25941 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } has no permanent_uuid. Determining permanent_uuid...
12:12:15.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.654569 25941 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } has no permanent_uuid. Determining permanent_uuid...
12:12:15.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.670590 25941 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } has no permanent_uuid. Determining permanent_uuid...
12:12:15.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.673243 25890 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:15.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.677861 25941 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:15.728 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.727486 25941 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 1
12:12:15.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.731838 25941 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:15.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.800304 25941 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 2
12:12:15.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.804910 25941 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:15.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.896368 25941 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 3
12:12:15.901 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.901356 25941 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:15.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.933444 25943 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
12:12:15.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.934007 25943 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
12:12:15.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.934523 25943 flags.cc:432] Enabled unsafe flag: --never_fsync=true
12:12:15.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.968484 25943 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
12:12:15.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.968775 25943 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
12:12:15.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.968971 25943 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
12:12:15.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.969159 25943 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
12:12:15.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.969354 25943 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
12:12:15.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:15.969492 25943 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:46413/jwks.json
12:12:15.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:15.991989 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } attempt: 7
12:12:16.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.004184 25866 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } has no permanent_uuid. Determining permanent_uuid...
12:12:16.011 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.010455 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.014899 25943 master_runner.cc:387] Master server non-default flags:
12:12:16.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:16.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:16.015 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:16.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:16.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data
12:12:16.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/wal
12:12:16.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
12:12:16.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:16.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:16.016 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:16.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
12:12:16.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.188:38273
12:12:16.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
12:12:16.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
12:12:16.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
12:12:16.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:46413/jwks.json
12:12:16.017 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:16.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:16.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data/info.pb
12:12:16.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.188
12:12:16.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:16.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
12:12:16.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:16.018 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/logs
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
12:12:16.019 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
12:12:16.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 8aadabe0236da0a312e7e3764fada9dc81e70c40
12:12:16.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
12:12:16.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 11:49:08 UTC on e7f111948823
12:12:16.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16857
12:12:16.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
12:12:16.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.016350 25943 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
12:12:16.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.018126 25943 file_cache.cc:492] Constructed file cache file cache with capacity 419430
12:12:16.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.029192 25955 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
12:12:16.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.044819 25941 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 4
12:12:16.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.052606 25941 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.069036 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 1
12:12:16.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.078989 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.160641 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 2
12:12:16.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.173269 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.258900 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 3
12:12:16.269 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.268568 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.352243 25941 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 5
12:12:16.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.361739 25941 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.410 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.409188 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 4
12:12:16.419 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.418212 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.701799 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 5
12:12:16.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.710769 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:16.910 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:16.909307 25941 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 6
12:12:16.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.943207 25941 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:17.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.263329 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 6
12:12:17.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:17.293740 25866 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.25.46.188:38273: Network error: Client connection negotiation failed: client connection to 127.25.46.188:38273: connect: Connection refused (error 111)
12:12:17.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:16.030095 25956 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
12:12:17.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:17.433992 25954 debug-util.cc:398] Leaking SignalData structure 0x7b0800028020 after lost signal to thread 25943
12:12:17.790 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:17.788647 25954 kernel_stack_watchdog.cc:198] Thread 25943 stuck at /home/jenkins-slave/workspace/build_and_test@2/src/kudu/util/thread.cc:642 for 401ms:
12:12:17.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
12:12:17.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
12:12:17.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
12:12:17.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
12:12:17.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:17.793582 25957 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1762 milliseconds
12:12:17.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:17.793733 25943 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.764s	user 0.643s	sys 1.110s
12:12:17.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:17.794059 25943 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.765s	user 0.643s	sys 1.110s
12:12:17.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:17.795221 25958 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
12:12:17.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.795303 25943 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
12:12:17.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.800004 25943 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
12:12:17.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.802680 25943 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
12:12:17.804 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.804083 25943 hybrid_clock.cc:648] HybridClock initialized: now 1783512737804056 us; error 44 us; skew 500 ppm
12:12:17.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.805126 25943 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
12:12:17.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.811579 25943 webserver.cc:533] Webserver started at http://127.25.46.188:37105/ using document root <none> and password file <none>
12:12:17.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.812475 25943 fs_manager.cc:362] Metadata directory not provided
12:12:17.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.812719 25943 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
12:12:17.813 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.813134 25943 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
12:12:17.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.817396 25943 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data/instance:
12:12:17.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "64ccb95fc60e4be4b761be60b359148f"
12:12:17.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:17 on dist-test-slave-zz1r"
12:12:17.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.818459 25943 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/wal/instance:
12:12:17.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "64ccb95fc60e4be4b761be60b359148f"
12:12:17.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:17 on dist-test-slave-zz1r"
12:12:17.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.825642 25943 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.005s	sys 0.001s
12:12:17.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.831427 25969 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
12:12:17.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.832631 25943 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.002s
12:12:17.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.832933 25943 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data,/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/wal
12:12:17.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "64ccb95fc60e4be4b761be60b359148f"
12:12:17.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:17 on dist-test-slave-zz1r"
12:12:17.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.833323 25943 fs_report.cc:389] FS layout report
12:12:17.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/wal
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/wal
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data/data
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
12:12:17.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
12:12:17.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
12:12:17.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
12:12:17.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
12:12:17.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
12:12:17.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
12:12:17.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.887663 25943 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
12:12:17.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.889106 25943 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
12:12:17.889 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.889521 25943 kserver.cc:163] Server-wide thread pool size limit: 3276
12:12:17.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.963366 25943 rpc_server.cc:307] RPC server started. Bound to: 127.25.46.188:38273
12:12:17.964 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.963441 26020 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.46.188:38273 every 8 connection(s)
12:12:17.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.966451 25943 server_base.cc:1193] Dumped server information to /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/data/info.pb
12:12:17.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.972375 26021 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
12:12:17.976 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.976342 25786 external_mini_cluster.cc:1434] Started /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu as pid 25943
12:12:17.977 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.976783 25786 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/master-2/wal/instance
12:12:17.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:17.982712 26021 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } has no permanent_uuid. Determining permanent_uuid...
12:12:18.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.003819 25941 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 7
12:12:18.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.028379 26021 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } has no permanent_uuid. Determining permanent_uuid...
12:12:18.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.039309 26021 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } has no permanent_uuid. Determining permanent_uuid...
12:12:18.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.043233 25941 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: Bootstrap starting.
12:12:18.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.049968 25941 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: Neither blocks nor log segments found. Creating new log.
12:12:18.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.052554 25941 log.cc:826] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: Log is configured to *not* fsync() on all Append() calls
12:12:18.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.057999 25941 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: No bootstrap required, opened a new log
12:12:18.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.062820 26021 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f: Bootstrap starting.
12:12:18.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.072157 26021 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f: Neither blocks nor log segments found. Creating new log.
12:12:18.075 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.074811 26021 log.cc:826] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f: Log is configured to *not* fsync() on all Append() calls
12:12:18.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.080857 26021 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f: No bootstrap required, opened a new log
12:12:18.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.086449 25941 raft_consensus.cc:359] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.087973 25941 raft_consensus.cc:385] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
12:12:18.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.088629 25941 raft_consensus.cc:740] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d9a0de64ccd447018ab1bd3cc05b94ab, State: Initialized, Role: FOLLOWER
12:12:18.090 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.090045 25941 consensus_queue.cc:260] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [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: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.094225 26033 sys_catalog.cc:455] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [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: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.096 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.096256 26033 sys_catalog.cc:458] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [sys.catalog]: This master's current role is: FOLLOWER
12:12:18.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.096323 25941 sys_catalog.cc:565] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [sys.catalog]: configured and running, proceeding with master startup.
12:12:18.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.105057 26021 raft_consensus.cc:359] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.106066 26021 raft_consensus.cc:385] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
12:12:18.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.106354 26021 raft_consensus.cc:740] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 64ccb95fc60e4be4b761be60b359148f, State: Initialized, Role: FOLLOWER
12:12:18.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.107352 26021 consensus_queue.cc:260] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [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: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.111053 26039 sys_catalog.cc:455] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [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: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.112298 26039 sys_catalog.cc:458] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: This master's current role is: FOLLOWER
12:12:18.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.113076 26021 sys_catalog.cc:565] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: configured and running, proceeding with master startup.
12:12:18.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:18.144757 25975 tablet.cc:2406] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f: Can't schedule compaction. Clean time has not been advanced past its initial value.
12:12:18.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:18.145679 26048 catalog_manager.cc:1594] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
12:12:18.147 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:18.146008 26048 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
12:12:18.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:18.150763 26057 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
12:12:18.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:18.151651 26057 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
12:12:18.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.129079 25868 master_runner.cc:187] Error getting master registration for 127.25.46.190:43805: OK, instance_id { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" instance_seqno: 1783512733584992 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
12:12:18.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.167946 25868 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
12:12:18.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.148532 25943 master_runner.cc:187] Error getting master registration for 127.25.46.190:43805: OK, instance_id { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" instance_seqno: 1783512733584992 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
12:12:18.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.181916 25943 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
12:12:18.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.218257 26039 raft_consensus.cc:493] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
12:12:18.219 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.218879 26039 raft_consensus.cc:515] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.221836 26039 leader_election.cc:290] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers cb895826d0f34856aa35036ceedf660e (127.25.46.190:43805), d9a0de64ccd447018ab1bd3cc05b94ab (127.25.46.189:45923)
12:12:18.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.223933 25916 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "64ccb95fc60e4be4b761be60b359148f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" is_pre_election: true
12:12:18.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.224973 25916 raft_consensus.cc:2468] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 64ccb95fc60e4be4b761be60b359148f in term 0.
12:12:18.227 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.226564 25971 leader_election.cc:304] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [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: 64ccb95fc60e4be4b761be60b359148f, d9a0de64ccd447018ab1bd3cc05b94ab; no voters: 
12:12:18.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.227670 26039 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Leader pre-election won for term 1
12:12:18.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.228040 26039 raft_consensus.cc:493] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
12:12:18.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.228381 26039 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 0 FOLLOWER]: Advancing to term 1
12:12:18.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.240784 26039 raft_consensus.cc:515] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.253715 26039 leader_election.cc:290] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [CANDIDATE]: Term 1 election: Requested vote from peers cb895826d0f34856aa35036ceedf660e (127.25.46.190:43805), d9a0de64ccd447018ab1bd3cc05b94ab (127.25.46.189:45923)
12:12:18.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.254009 25916 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "64ccb95fc60e4be4b761be60b359148f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab"
12:12:18.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.255025 25916 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [term 0 FOLLOWER]: Advancing to term 1
12:12:18.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.270004 25916 raft_consensus.cc:2468] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 64ccb95fc60e4be4b761be60b359148f in term 1.
12:12:18.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.271773 25971 leader_election.cc:304] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [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: 64ccb95fc60e4be4b761be60b359148f, d9a0de64ccd447018ab1bd3cc05b94ab; no voters: 
12:12:18.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.272840 26039 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 1 FOLLOWER]: Leader election won for term 1
12:12:18.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:18.278592 25895 tablet.cc:2406] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: Can't schedule compaction. Clean time has not been advanced past its initial value.
12:12:18.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.280912 26039 raft_consensus.cc:697] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [term 1 LEADER]: Becoming Leader. State: Replica: 64ccb95fc60e4be4b761be60b359148f, State: Running, Role: LEADER
12:12:18.283 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.282035 26039 consensus_queue.cc:237] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [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: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.306106 26059 sys_catalog.cc:455] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: SysCatalogTable state changed. Reason: New leader 64ccb95fc60e4be4b761be60b359148f. Latest consensus state: current_term: 1 leader_uuid: "64ccb95fc60e4be4b761be60b359148f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.307031 26059 sys_catalog.cc:458] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: This master's current role is: LEADER
12:12:18.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.310883 26061 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
12:12:18.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.323841 26061 catalog_manager.cc:1520] Initializing Kudu cluster ID...
12:12:18.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.327244 25866 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } attempt: 7
12:12:18.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.357321 25916 raft_consensus.cc:1275] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [term 1 FOLLOWER]: Refusing update from remote peer 64ccb95fc60e4be4b761be60b359148f: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
12:12:18.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.359759 26059 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [LEADER]: Connected to new peer: Peer: permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
12:12:18.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.402002 26033 sys_catalog.cc:455] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [sys.catalog]: SysCatalogTable state changed. Reason: New leader 64ccb95fc60e4be4b761be60b359148f. Latest consensus state: current_term: 1 leader_uuid: "64ccb95fc60e4be4b761be60b359148f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.407778 26033 sys_catalog.cc:458] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [sys.catalog]: This master's current role is: FOLLOWER
12:12:18.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.414968 26063 mvcc.cc:204] Tried to move back new op lower bound from 7305268176291737600 to 7305268176017399808. Current Snapshot: MvccSnapshot[applied={T|T < 7305268176291737600}]
12:12:18.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.424261 25866 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e: Bootstrap starting.
12:12:18.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.438180 26033 sys_catalog.cc:455] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "64ccb95fc60e4be4b761be60b359148f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.438987 26033 sys_catalog.cc:458] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab [sys.catalog]: This master's current role is: FOLLOWER
12:12:18.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.443908 26059 sys_catalog.cc:455] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "64ccb95fc60e4be4b761be60b359148f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.444661 26059 sys_catalog.cc:458] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: This master's current role is: LEADER
12:12:18.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.446123 26061 catalog_manager.cc:1383] Generated new cluster ID: 87da08a9b0b94a83b58734623807d065
12:12:18.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.446453 26061 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
12:12:18.455 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.454617 25866 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e: Neither blocks nor log segments found. Creating new log.
12:12:18.461 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.460827 25866 log.cc:826] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e: Log is configured to *not* fsync() on all Append() calls
12:12:18.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.487998 25866 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e: No bootstrap required, opened a new log
12:12:18.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.495648 26061 catalog_manager.cc:1406] Generated new certificate authority record
12:12:18.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.507635 26061 catalog_manager.cc:1540] Loading token signing keys...
12:12:18.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.568288 25866 raft_consensus.cc:359] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.570430 25866 raft_consensus.cc:385] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
12:12:18.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.571269 25866 raft_consensus.cc:740] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: cb895826d0f34856aa35036ceedf660e, State: Initialized, Role: FOLLOWER
12:12:18.576 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.573534 25866 consensus_queue.cc:260] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [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: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } }
12:12:18.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.581557 26061 catalog_manager.cc:6070] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f: Generated new TSK 0
12:12:18.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.583307 26061 catalog_manager.cc:1550] Initializing in-progress tserver states...
12:12:18.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.591583 26069 sys_catalog.cc:455] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [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: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.595526 26069 sys_catalog.cc:458] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [sys.catalog]: This master's current role is: FOLLOWER
12:12:18.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.595992 25839 raft_consensus.cc:3060] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [term 0 FOLLOWER]: Advancing to term 1
12:12:18.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.596841 25841 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "64ccb95fc60e4be4b761be60b359148f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "cb895826d0f34856aa35036ceedf660e" is_pre_election: true
12:12:18.599 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.594926 25840 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "64ccb95fc60e4be4b761be60b359148f" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "cb895826d0f34856aa35036ceedf660e"
12:12:18.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.599673 25866 sys_catalog.cc:565] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [sys.catalog]: configured and running, proceeding with master startup.
12:12:18.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.605435 25839 raft_consensus.cc:1275] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [term 1 FOLLOWER]: Refusing update from remote peer 64ccb95fc60e4be4b761be60b359148f: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
12:12:18.609 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.607509 26059 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [LEADER]: Connected to new peer: Peer: permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
12:12:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.620595 25786 external_mini_cluster.cc:1372] Running /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/wal
12:12:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data
12:12:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
12:12:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
12:12:18.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/logs
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data/info.pb
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
12:12:18.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.129:0
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.25.46.129
12:12:18.623 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.129
12:12:18.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:18.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:18.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:18.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:18.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:18.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
12:12:18.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.738299 26069 sys_catalog.cc:455] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [sys.catalog]: SysCatalogTable state changed. Reason: New leader 64ccb95fc60e4be4b761be60b359148f. Latest consensus state: current_term: 1 leader_uuid: "64ccb95fc60e4be4b761be60b359148f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.738432 26075 sys_catalog.cc:455] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "64ccb95fc60e4be4b761be60b359148f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:18.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.739123 26069 sys_catalog.cc:458] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [sys.catalog]: This master's current role is: FOLLOWER
12:12:18.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.739133 26075 sys_catalog.cc:458] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e [sys.catalog]: This master's current role is: FOLLOWER
12:12:18.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.834401 26087 catalog_manager.cc:1295] Loaded cluster ID: 87da08a9b0b94a83b58734623807d065
12:12:18.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.834733 26087 catalog_manager.cc:1588] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e: loading cluster ID for follower catalog manager: success
12:12:18.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.859457 26087 catalog_manager.cc:1610] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e: acquiring CA information for follower catalog manager: success
12:12:18.865 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:18.865085 26087 catalog_manager.cc:1638] T 00000000000000000000000000000000 P cb895826d0f34856aa35036ceedf660e: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
12:12:19.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.001191 26059 sys_catalog.cc:455] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "64ccb95fc60e4be4b761be60b359148f" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "cb895826d0f34856aa35036ceedf660e" member_type: VOTER last_known_addr { host: "127.25.46.190" port: 43805 } } peers { permanent_uuid: "d9a0de64ccd447018ab1bd3cc05b94ab" member_type: VOTER last_known_addr { host: "127.25.46.189" port: 45923 } } peers { permanent_uuid: "64ccb95fc60e4be4b761be60b359148f" member_type: VOTER last_known_addr { host: "127.25.46.188" port: 38273 } } }
12:12:19.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.002012 26059 sys_catalog.cc:458] T 00000000000000000000000000000000 P 64ccb95fc60e4be4b761be60b359148f [sys.catalog]: This master's current role is: LEADER
12:12:19.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.152266 26048 catalog_manager.cc:1295] Loaded cluster ID: 87da08a9b0b94a83b58734623807d065
12:12:19.153 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.153128 26048 catalog_manager.cc:1588] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: loading cluster ID for follower catalog manager: success
12:12:19.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.169656 26048 catalog_manager.cc:1610] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: acquiring CA information for follower catalog manager: success
12:12:19.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.184600 26048 catalog_manager.cc:1638] T 00000000000000000000000000000000 P d9a0de64ccd447018ab1bd3cc05b94ab: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
12:12:19.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:19.210528 26072 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
12:12:19.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:19.212002 26072 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
12:12:19.213 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:19.213156 26072 flags.cc:432] Enabled unsafe flag: --never_fsync=true
12:12:19.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:19.277045 26072 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
12:12:19.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:19.279170 26072 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.25.46.129
12:12:19.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.353560 26072 tablet_server_runner.cc:78] Tablet server non-default flags:
12:12:19.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:19.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:19.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:19.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:19.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data
12:12:19.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/wal
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.129:0
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data/info.pb
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.129
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:19.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.25.46.129
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/logs
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
12:12:19.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
12:12:19.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 8aadabe0236da0a312e7e3764fada9dc81e70c40
12:12:19.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
12:12:19.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 11:49:08 UTC on e7f111948823
12:12:19.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16857
12:12:19.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
12:12:19.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.365861 26072 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
12:12:19.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:19.369199 26072 file_cache.cc:492] Constructed file cache file cache with capacity 419430
12:12:19.405 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:19.404448 26094 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
12:12:21.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:20.792907 26093 debug-util.cc:398] Leaking SignalData structure 0x7b0800028000 after lost signal to thread 26072
12:12:21.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:21.416602 26072 thread.cc:641] GCE (cloud detector) Time spent creating pthread: real 2.014s	user 0.651s	sys 1.067s
12:12:21.421 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:21.421720 26072 thread.cc:608] GCE (cloud detector) Time spent starting thread: real 2.020s	user 0.652s	sys 1.067s
12:12:21.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:19.404860 26095 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
12:12:21.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.436738 26072 server_base.cc:1061] running on GCE node
12:12:21.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:21.438771 26100 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
12:12:21.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.440656 26072 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
12:12:21.446 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.445590 26072 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
12:12:21.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.447448 26072 hybrid_clock.cc:648] HybridClock initialized: now 1783512741447406 us; error 54 us; skew 500 ppm
12:12:21.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.448942 26072 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
12:12:21.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.468752 26072 webserver.cc:533] Webserver started at http://127.25.46.129:41289/ using document root <none> and password file <none>
12:12:21.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.470486 26072 fs_manager.cc:362] Metadata directory not provided
12:12:21.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.471154 26072 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
12:12:21.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.471968 26072 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
12:12:21.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.478543 26072 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data/instance:
12:12:21.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3e5b992f190644e9948a82d48d3a5e9e"
12:12:21.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:21 on dist-test-slave-zz1r"
12:12:21.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.480705 26072 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/wal/instance:
12:12:21.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3e5b992f190644e9948a82d48d3a5e9e"
12:12:21.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:21 on dist-test-slave-zz1r"
12:12:21.502 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.501576 26072 fs_manager.cc:696] Time spent creating directory manager: real 0.020s	user 0.006s	sys 0.006s
12:12:21.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.516674 26105 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
12:12:21.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.519480 26072 fs_manager.cc:730] Time spent opening block manager: real 0.012s	user 0.006s	sys 0.003s
12:12:21.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.521865 26072 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data,/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/wal
12:12:21.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3e5b992f190644e9948a82d48d3a5e9e"
12:12:21.522 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:21 on dist-test-slave-zz1r"
12:12:21.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.522886 26072 fs_report.cc:389] FS layout report
12:12:21.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
12:12:21.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/wal
12:12:21.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/wal
12:12:21.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data/data
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
12:12:21.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
12:12:21.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.661465 26072 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
12:12:21.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.666435 26072 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
12:12:21.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.667842 26072 kserver.cc:163] Server-wide thread pool size limit: 3276
12:12:21.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.681872 26072 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
12:12:21.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.690440 26072 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
12:12:21.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.691157 26072 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.001s	user 0.001s	sys 0.001s
12:12:21.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.691757 26072 ts_tablet_manager.cc:616] Registered 0 tablets
12:12:21.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:21.692178 26072 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
12:12:22.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.349598 26072 rpc_server.cc:307] RPC server started. Bound to: 127.25.46.129:38841
12:12:22.350 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.349740 26218 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.46.129:38841 every 8 connection(s)
12:12:22.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.354038 26072 server_base.cc:1193] Dumped server information to /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/data/info.pb
12:12:22.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.356541 25786 external_mini_cluster.cc:1434] Started /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu as pid 26072
12:12:22.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.357154 25786 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-0/wal/instance
12:12:22.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.393260 25786 external_mini_cluster.cc:1372] Running /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/wal
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:22.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/logs
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data/info.pb
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.130:0
12:12:22.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.25.46.130
12:12:22.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.130
12:12:22.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:22.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:22.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:22.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:22.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:22.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
12:12:22.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.462352 26219 heartbeater.cc:344] Connected to a master server at 127.25.46.188:38273
12:12:22.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.462908 26219 heartbeater.cc:461] Registering TS with master...
12:12:22.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.464449 26219 heartbeater.cc:507] Master 127.25.46.188:38273 requested a full tablet report, sending...
12:12:22.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.469066 25986 ts_manager.cc:194] Registered new tserver with Master: 3e5b992f190644e9948a82d48d3a5e9e (127.25.46.129:38841)
12:12:22.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.472755 25986 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.25.46.129:34515
12:12:22.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.475709 26220 heartbeater.cc:344] Connected to a master server at 127.25.46.189:45923
12:12:22.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.476094 26220 heartbeater.cc:461] Registering TS with master...
12:12:22.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.477035 26220 heartbeater.cc:507] Master 127.25.46.189:45923 requested a full tablet report, sending...
12:12:22.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.480470 25906 ts_manager.cc:194] Registered new tserver with Master: 3e5b992f190644e9948a82d48d3a5e9e (127.25.46.129:38841)
12:12:22.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.488556 26223 heartbeater.cc:344] Connected to a master server at 127.25.46.190:43805
12:12:22.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.488890 26223 heartbeater.cc:461] Registering TS with master...
12:12:22.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.489513 26223 heartbeater.cc:507] Master 127.25.46.190:43805 requested a full tablet report, sending...
12:12:22.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.494238 25831 ts_manager.cc:194] Registered new tserver with Master: 3e5b992f190644e9948a82d48d3a5e9e (127.25.46.129:38841)
12:12:22.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:22.809458 26226 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
12:12:22.810 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:22.809921 26226 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
12:12:22.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:22.810689 26226 flags.cc:432] Enabled unsafe flag: --never_fsync=true
12:12:22.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:22.843676 26226 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
12:12:22.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:22.844478 26226 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.25.46.130
12:12:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.880076 26226 tablet_server_runner.cc:78] Tablet server non-default flags:
12:12:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data
12:12:22.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/wal
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.130:0
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data/info.pb
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.130
12:12:22.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.25.46.130
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/logs
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
12:12:22.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 8aadabe0236da0a312e7e3764fada9dc81e70c40
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 11:49:08 UTC on e7f111948823
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16857
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.881541 26226 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
12:12:22.883 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:22.883417 26226 file_cache.cc:492] Constructed file cache file cache with capacity 419430
12:12:22.896 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:22.895588 26235 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
12:12:23.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:23.482985 26219 heartbeater.cc:499] Master 127.25.46.188:38273 was elected leader, sending a full tablet report...
12:12:24.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:24.300966 26234 debug-util.cc:398] Leaking SignalData structure 0x7b0800028000 after lost signal to thread 26226
12:12:24.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:24.339293 26234 kernel_stack_watchdog.cc:198] Thread 26226 stuck at /home/jenkins-slave/workspace/build_and_test@2/src/kudu/util/thread.cc:642 for 401ms:
12:12:24.339 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
12:12:24.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
12:12:24.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
12:12:24.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
12:12:24.340 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:22.897030 26236 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
12:12:24.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:24.340585 26226 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.444s	user 0.000s	sys 0.002s
12:12:24.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:24.341429 26226 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.445s	user 0.000s	sys 0.002s
12:12:24.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:24.346863 26237 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1443 milliseconds
12:12:24.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:24.349135 26238 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
12:12:24.351 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.351025 26226 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
12:12:24.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.352721 26226 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
12:12:24.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.355564 26226 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
12:12:24.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.357389 26226 hybrid_clock.cc:648] HybridClock initialized: now 1783512744357325 us; error 48 us; skew 500 ppm
12:12:24.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.358675 26226 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
12:12:24.367 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.367018 26226 webserver.cc:533] Webserver started at http://127.25.46.130:40197/ using document root <none> and password file <none>
12:12:24.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.368453 26226 fs_manager.cc:362] Metadata directory not provided
12:12:24.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.368767 26226 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
12:12:24.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.369468 26226 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
12:12:24.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.376360 26226 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data/instance:
12:12:24.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "be3b11c04416466d80c93fe84138e9f3"
12:12:24.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:24 on dist-test-slave-zz1r"
12:12:24.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.377830 26226 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/wal/instance:
12:12:24.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "be3b11c04416466d80c93fe84138e9f3"
12:12:24.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:24 on dist-test-slave-zz1r"
12:12:24.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.390733 26226 fs_manager.cc:696] Time spent creating directory manager: real 0.012s	user 0.001s	sys 0.012s
12:12:24.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.400048 26246 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
12:12:24.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.401600 26226 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.002s	sys 0.003s
12:12:24.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.401966 26226 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data,/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/wal
12:12:24.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "be3b11c04416466d80c93fe84138e9f3"
12:12:24.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:24 on dist-test-slave-zz1r"
12:12:24.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.402762 26226 fs_report.cc:389] FS layout report
12:12:24.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
12:12:24.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/wal
12:12:24.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/wal
12:12:24.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data/data
12:12:24.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
12:12:24.404 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
12:12:24.465 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.464687 26226 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
12:12:24.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.466485 26226 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
12:12:24.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.467100 26226 kserver.cc:163] Server-wide thread pool size limit: 3276
12:12:24.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.470187 26226 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
12:12:24.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.475076 26226 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
12:12:24.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.475386 26226 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
12:12:24.475 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.475701 26226 ts_tablet_manager.cc:616] Registered 0 tablets
12:12:24.476 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.475945 26226 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
12:12:24.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.645072 26226 rpc_server.cc:307] RPC server started. Bound to: 127.25.46.130:33909
12:12:24.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.645246 26358 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.46.130:33909 every 8 connection(s)
12:12:24.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.648010 26226 server_base.cc:1193] Dumped server information to /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/data/info.pb
12:12:24.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.656441 25786 external_mini_cluster.cc:1434] Started /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu as pid 26226
12:12:24.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.656937 25786 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-1/wal/instance
12:12:24.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.675282 25786 external_mini_cluster.cc:1372] Running /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/wal
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/logs
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data/info.pb
12:12:24.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.131:0
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.25.46.131
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.131
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:24.677 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:24.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:24.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:24.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:24.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
12:12:24.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.681591 26359 heartbeater.cc:344] Connected to a master server at 127.25.46.188:38273
12:12:24.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.682190 26359 heartbeater.cc:461] Registering TS with master...
12:12:24.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.683964 26359 heartbeater.cc:507] Master 127.25.46.188:38273 requested a full tablet report, sending...
12:12:24.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.687769 25986 ts_manager.cc:194] Registered new tserver with Master: be3b11c04416466d80c93fe84138e9f3 (127.25.46.130:33909)
12:12:24.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.689697 25986 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.25.46.130:45507
12:12:24.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.698451 26360 heartbeater.cc:344] Connected to a master server at 127.25.46.189:45923
12:12:24.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.698900 26360 heartbeater.cc:461] Registering TS with master...
12:12:24.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.699595 26360 heartbeater.cc:507] Master 127.25.46.189:45923 requested a full tablet report, sending...
12:12:24.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.702672 25906 ts_manager.cc:194] Registered new tserver with Master: be3b11c04416466d80c93fe84138e9f3 (127.25.46.130:33909)
12:12:24.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.709762 26361 heartbeater.cc:344] Connected to a master server at 127.25.46.190:43805
12:12:24.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.710076 26361 heartbeater.cc:461] Registering TS with master...
12:12:24.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.710683 26361 heartbeater.cc:507] Master 127.25.46.190:43805 requested a full tablet report, sending...
12:12:24.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:24.713065 25831 ts_manager.cc:194] Registered new tserver with Master: be3b11c04416466d80c93fe84138e9f3 (127.25.46.130:33909)
12:12:25.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:25.012727 26368 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
12:12:25.013 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:25.013336 26368 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
12:12:25.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:25.014060 26368 flags.cc:432] Enabled unsafe flag: --never_fsync=true
12:12:25.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:25.049360 26368 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
12:12:25.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:25.050365 26368 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.25.46.131
12:12:25.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:25.087297 26368 tablet_server_runner.cc:78] Tablet server non-default flags:
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.25.46.148:38561
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/wal
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.25.46.131:0
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data/info.pb
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.25.46.131
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.25.46.190:43805,127.25.46.189:45923,127.25.46.188:38273
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
12:12:25.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.25.46.131
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/logs
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 8aadabe0236da0a312e7e3764fada9dc81e70c40
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 08 Jul 2026 11:49:08 UTC on e7f111948823
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16857
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
12:12:25.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:25.089053 26368 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
12:12:25.091 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:25.090924 26368 file_cache.cc:492] Constructed file cache file cache with capacity 419430
12:12:25.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:25.104457 26374 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
12:12:25.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:25.693914 26359 heartbeater.cc:499] Master 127.25.46.188:38273 was elected leader, sending a full tablet report...
12:12:26.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:25.105451 26375 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
12:12:26.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:26.507359 26373 debug-util.cc:398] Leaking SignalData structure 0x7b0800028000 after lost signal to thread 26368
12:12:26.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:26.806447 26368 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.702s	user 0.542s	sys 1.128s
12:12:26.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:26.806792 26368 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.702s	user 0.543s	sys 1.128s
12:12:26.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:26.807509 26376 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1700 milliseconds
12:12:26.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:26.808827 26377 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
12:12:26.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.808890 26368 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
12:12:26.812 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.812137 26368 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
12:12:26.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.814153 26368 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
12:12:26.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.815554 26368 hybrid_clock.cc:648] HybridClock initialized: now 1783512746815522 us; error 44 us; skew 500 ppm
12:12:26.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.816313 26368 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
12:12:26.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.822190 26368 webserver.cc:533] Webserver started at http://127.25.46.131:34623/ using document root <none> and password file <none>
12:12:26.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.823109 26368 fs_manager.cc:362] Metadata directory not provided
12:12:26.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.823307 26368 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
12:12:26.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.823710 26368 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
12:12:26.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.828017 26368 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data/instance:
12:12:26.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "554126118c88472eab1e5f18b063f660"
12:12:26.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:26 on dist-test-slave-zz1r"
12:12:26.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.829193 26368 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/wal/instance:
12:12:26.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "554126118c88472eab1e5f18b063f660"
12:12:26.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:26 on dist-test-slave-zz1r"
12:12:26.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:26.835129 25862 debug-util.cc:398] Leaking SignalData structure 0x7b08000aaa40 after lost signal to thread 25799
12:12:26.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:26.835685 25862 debug-util.cc:398] Leaking SignalData structure 0x7b080008e5a0 after lost signal to thread 25865
12:12:26.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.836791 26368 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.000s	sys 0.008s
12:12:26.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.844861 26385 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
12:12:26.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.846153 26368 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.006s	sys 0.000s
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.846550 26368 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data,/tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/wal
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "554126118c88472eab1e5f18b063f660"
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-08 12:12:26 on dist-test-slave-zz1r"
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.847005 26368 fs_report.cc:389] FS layout report
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/wal
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/wal
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data/data
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
12:12:26.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
12:12:26.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
12:12:26.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
12:12:26.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
12:12:26.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
12:12:26.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
12:12:26.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
12:12:26.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
12:12:26.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.918279 26368 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
12:12:26.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.919845 26368 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
12:12:26.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.920267 26368 kserver.cc:163] Server-wide thread pool size limit: 3276
12:12:26.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.923074 26368 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
12:12:26.927 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.927672 26368 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
12:12:26.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.927917 26368 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
12:12:26.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.928223 26368 ts_tablet_manager.cc:616] Registered 0 tablets
12:12:26.928 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:26.928447 26368 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
12:12:27.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.102310 26368 rpc_server.cc:307] RPC server started. Bound to: 127.25.46.131:45131
12:12:27.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.102429 26497 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.25.46.131:45131 every 8 connection(s)
12:12:27.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.106438 26368 server_base.cc:1193] Dumped server information to /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/data/info.pb
12:12:27.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.111778 25786 external_mini_cluster.cc:1434] Started /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu as pid 26368
12:12:27.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.112238 25786 external_mini_cluster.cc:1448] Reading /tmp/dist-test-taska0vbhI/test-tmp/mini-kudu-cluster17672219206637239873/ts-2/wal/instance
12:12:27.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.186944 26499 heartbeater.cc:344] Connected to a master server at 127.25.46.189:45923
12:12:27.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.187472 26499 heartbeater.cc:461] Registering TS with master...
12:12:27.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.188802 26499 heartbeater.cc:507] Master 127.25.46.189:45923 requested a full tablet report, sending...
12:12:27.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.191807 25906 ts_manager.cc:194] Registered new tserver with Master: 554126118c88472eab1e5f18b063f660 (127.25.46.131:45131)
12:12:27.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.193684 26498 heartbeater.cc:344] Connected to a master server at 127.25.46.188:38273
12:12:27.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.194036 26498 heartbeater.cc:461] Registering TS with master...
12:12:27.195 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.195171 26498 heartbeater.cc:507] Master 127.25.46.188:38273 requested a full tablet report, sending...
12:12:27.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.197479 26500 heartbeater.cc:344] Connected to a master server at 127.25.46.190:43805
12:12:27.198 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.197824 26500 heartbeater.cc:461] Registering TS with master...
12:12:27.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.198390 25986 ts_manager.cc:194] Registered new tserver with Master: 554126118c88472eab1e5f18b063f660 (127.25.46.131:45131)
12:12:27.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.198767 26500 heartbeater.cc:507] Master 127.25.46.190:43805 requested a full tablet report, sending...
12:12:27.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.200351 25986 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.25.46.131:55063
12:12:27.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.201905 25831 ts_manager.cc:194] Registered new tserver with Master: 554126118c88472eab1e5f18b063f660 (127.25.46.131:45131)
12:12:27.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:27.220069 25786 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
12:12:27.236 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
12:12:27.238 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

12:12:27.248 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.25.46.190"
      port: 43805
    }
    bound_http_address {
      host: "127.25.46.190"
      port: 32945
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.25.46.189"
      port: 45923
    }
    bound_http_address {
      host: "127.25.46.189"
      port: 36829
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.25.46.188"
      port: 38273
    }
    bound_http_address {
      host: "127.25.46.188"
      port: 37105
    }
  }
}

12:12:27.252 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

12:12:27.255 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.25.46.129"
      port: 38841
    }
    bound_http_address {
      host: "127.25.46.129"
      port: 41289
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.25.46.130"
      port: 33909
    }
    bound_http_address {
      host: "127.25.46.130"
      port: 40197
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.25.46.131"
      port: 45131
    }
    bound_http_address {
      host: "127.25.46.131"
      port: 34623
    }
  }
}

12:12:27.256 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
12:12:27.489 [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.doOneAttempt(RetryRule.java:225)
	at org.apache.kudu.test.junit.RetryRule$RetryStatement.evaluate(RetryRule.java:246)
	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)
12:12:27.563 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
12:12:27.743 [DEBUG - main] (Connection.java:748) [peer master-127.25.46.188:38273(127.25.46.188:38273)] connecting to peer
12:12:27.798 [DEBUG - main] (Connection.java:748) [peer master-127.25.46.190:43805(127.25.46.190:43805)] connecting to peer
12:12:27.800 [DEBUG - main] (Connection.java:748) [peer master-127.25.46.189:45923(127.25.46.189:45923)] connecting to peer
12:12:27.821 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.25.46.190:43805(127.25.46.190:43805)] Successfully connected to peer
12:12:27.825 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.25.46.188:38273(127.25.46.188:38273)] Successfully connected to peer
12:12:27.831 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.25.46.189:45923(127.25.46.189:45923)] Successfully connected to peer
12:12:27.946 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.25.46.188
12:12:27.946 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.25.46.189
12:12:27.946 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.25.46.190
12:12:28.204 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.204363 26498 heartbeater.cc:499] Master 127.25.46.188:38273 was elected leader, sending a full tablet report...
12:12:28.239 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x85fcf2da, L:/127.0.0.1:34540 - R:/127.25.46.190:43805] using SASL/PLAIN
12:12:28.239 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xc2be6589, L:/127.0.0.1:58684 - R:/127.25.46.188:38273] using SASL/PLAIN
12:12:28.242 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0x08c13698, L:/127.0.0.1:57192 - R:/127.25.46.189:45923] using SASL/PLAIN
12:12:28.292 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
12:12:28.294 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=592), Traces: [0ms] refreshing cache from master, [26ms] Sub RPC ConnectToMaster: sending RPC to server master-127.25.46.188:38273, [93ms] Sub RPC ConnectToMaster: sending RPC to server master-127.25.46.190:43805, [95ms] Sub RPC ConnectToMaster: sending RPC to server master-127.25.46.189:45923, [552ms] Sub RPC ConnectToMaster: received response from server master-127.25.46.189:45923: OK, [552ms] Sub RPC ConnectToMaster: received response from server master-127.25.46.190:43805: OK, [554ms] Sub RPC ConnectToMaster: received response from server master-127.25.46.188:38273: OK, deferred=null) after lookup
12:12:28.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.360762 25986 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:58684:
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1783512730557"
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
12:12:28.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
12:12:28.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
12:12:28.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
12:12:28.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
12:12:28.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
12:12:28.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
12:12:28.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:28.365190 25986 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-1783512730557 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
12:12:28.450 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:894) Opened table 9c7e78cadce749d8859174f1ad270ed0
12:12:28.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.518729 26294 tablet_service.cc:1511] Processing CreateTablet for tablet f5a989bef8304e709e26e3741fd3f112 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783512730557 [id=9c7e78cadce749d8859174f1ad270ed0]), partition=RANGE (key) PARTITION UNBOUNDED
12:12:28.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.521230 26294 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet f5a989bef8304e709e26e3741fd3f112. 1 dirs total, 0 dirs full, 0 dirs failed
12:12:28.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.535483 26154 tablet_service.cc:1511] Processing CreateTablet for tablet f5a989bef8304e709e26e3741fd3f112 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783512730557 [id=9c7e78cadce749d8859174f1ad270ed0]), partition=RANGE (key) PARTITION UNBOUNDED
12:12:28.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.537766 26154 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet f5a989bef8304e709e26e3741fd3f112. 1 dirs total, 0 dirs full, 0 dirs failed
12:12:28.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.542870 26433 tablet_service.cc:1511] Processing CreateTablet for tablet f5a989bef8304e709e26e3741fd3f112 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783512730557 [id=9c7e78cadce749d8859174f1ad270ed0]), partition=RANGE (key) PARTITION UNBOUNDED
12:12:28.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.552306 26433 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet f5a989bef8304e709e26e3741fd3f112. 1 dirs total, 0 dirs full, 0 dirs failed
12:12:28.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.567174 26535 tablet_bootstrap.cc:492] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3: Bootstrap starting.
12:12:28.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.577899 26535 tablet_bootstrap.cc:654] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3: Neither blocks nor log segments found. Creating new log.
12:12:28.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.580386 26535 log.cc:826] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3: Log is configured to *not* fsync() on all Append() calls
12:12:28.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.588333 26536 tablet_bootstrap.cc:492] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e: Bootstrap starting.
12:12:28.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.596591 26536 tablet_bootstrap.cc:654] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e: Neither blocks nor log segments found. Creating new log.
12:12:28.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.599119 26536 log.cc:826] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e: Log is configured to *not* fsync() on all Append() calls
12:12:28.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.601612 26535 tablet_bootstrap.cc:492] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3: No bootstrap required, opened a new log
12:12:28.616 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.603860 26535 ts_tablet_manager.cc:1403] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3: Time spent bootstrapping tablet: real 0.037s	user 0.017s	sys 0.013s
12:12:28.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.648367 26539 tablet_bootstrap.cc:492] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660: Bootstrap starting.
12:12:28.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.641710 26535 raft_consensus.cc:359] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.653491 26535 raft_consensus.cc:385] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
12:12:28.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.653813 26535 raft_consensus.cc:740] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: be3b11c04416466d80c93fe84138e9f3, State: Initialized, Role: FOLLOWER
12:12:28.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.655299 26535 consensus_queue.cc:260] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [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: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.668324 26535 ts_tablet_manager.cc:1434] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3: Time spent starting tablet: real 0.064s	user 0.032s	sys 0.011s
12:12:28.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.669428 26536 tablet_bootstrap.cc:492] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e: No bootstrap required, opened a new log
12:12:28.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.669970 26536 ts_tablet_manager.cc:1403] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e: Time spent bootstrapping tablet: real 0.082s	user 0.018s	sys 0.028s
12:12:28.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.682006 26539 tablet_bootstrap.cc:654] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660: Neither blocks nor log segments found. Creating new log.
12:12:28.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.697104 26539 log.cc:826] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660: Log is configured to *not* fsync() on all Append() calls
12:12:28.703 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:28.701653 26366 tablet.cc:2406] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3: Can't schedule compaction. Clean time has not been advanced past its initial value.
12:12:28.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.707038 26539 tablet_bootstrap.cc:492] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660: No bootstrap required, opened a new log
12:12:28.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.707681 26539 ts_tablet_manager.cc:1403] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660: Time spent bootstrapping tablet: real 0.060s	user 0.008s	sys 0.012s
12:12:28.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.729153 26536 raft_consensus.cc:359] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.730 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.730517 26536 raft_consensus.cc:385] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
12:12:28.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.731068 26536 raft_consensus.cc:740] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3e5b992f190644e9948a82d48d3a5e9e, State: Initialized, Role: FOLLOWER
12:12:28.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.732331 26536 consensus_queue.cc:260] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e [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: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.738600 26539 raft_consensus.cc:359] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.739609 26539 raft_consensus.cc:385] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
12:12:28.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.739940 26539 raft_consensus.cc:740] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 554126118c88472eab1e5f18b063f660, State: Initialized, Role: FOLLOWER
12:12:28.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.741003 26539 consensus_queue.cc:260] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660 [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: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.745971 26536 ts_tablet_manager.cc:1434] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e: Time spent starting tablet: real 0.049s	user 0.039s	sys 0.001s
12:12:28.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.757087 26539 ts_tablet_manager.cc:1434] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660: Time spent starting tablet: real 0.049s	user 0.029s	sys 0.016s
12:12:28.824 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.823764 26540 raft_consensus.cc:493] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
12:12:28.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.824241 26540 raft_consensus.cc:515] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.829844 26540 leader_election.cc:290] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 3e5b992f190644e9948a82d48d3a5e9e (127.25.46.129:38841), 554126118c88472eab1e5f18b063f660 (127.25.46.131:45131)
12:12:28.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.842762 26453 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "f5a989bef8304e709e26e3741fd3f112" candidate_uuid: "be3b11c04416466d80c93fe84138e9f3" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "554126118c88472eab1e5f18b063f660" is_pre_election: true
12:12:28.843 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.842721 26174 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "f5a989bef8304e709e26e3741fd3f112" candidate_uuid: "be3b11c04416466d80c93fe84138e9f3" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3e5b992f190644e9948a82d48d3a5e9e" is_pre_election: true
12:12:28.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.843827 26453 raft_consensus.cc:2468] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate be3b11c04416466d80c93fe84138e9f3 in term 0.
12:12:28.844 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.843825 26174 raft_consensus.cc:2468] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate be3b11c04416466d80c93fe84138e9f3 in term 0.
12:12:28.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.845446 26250 leader_election.cc:304] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [CANDIDATE]: Term 1 pre-election: Election decided. Result: candidate won. Election summary: received 3 responses out of 3 voters: 3 yes votes; 0 no votes. yes voters: 3e5b992f190644e9948a82d48d3a5e9e, 554126118c88472eab1e5f18b063f660, be3b11c04416466d80c93fe84138e9f3; no voters: 
12:12:28.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.846369 26540 raft_consensus.cc:2804] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Leader pre-election won for term 1
12:12:28.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.846786 26540 raft_consensus.cc:493] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
12:12:28.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.847157 26540 raft_consensus.cc:3060] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 0 FOLLOWER]: Advancing to term 1
12:12:28.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.855788 26540 raft_consensus.cc:515] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.858657 26540 leader_election.cc:290] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [CANDIDATE]: Term 1 election: Requested vote from peers 3e5b992f190644e9948a82d48d3a5e9e (127.25.46.129:38841), 554126118c88472eab1e5f18b063f660 (127.25.46.131:45131)
12:12:28.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.859043 26174 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "f5a989bef8304e709e26e3741fd3f112" candidate_uuid: "be3b11c04416466d80c93fe84138e9f3" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3e5b992f190644e9948a82d48d3a5e9e"
12:12:28.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.859663 26174 raft_consensus.cc:3060] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e [term 0 FOLLOWER]: Advancing to term 1
12:12:28.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.864073 26453 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "f5a989bef8304e709e26e3741fd3f112" candidate_uuid: "be3b11c04416466d80c93fe84138e9f3" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "554126118c88472eab1e5f18b063f660"
12:12:28.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.864738 26453 raft_consensus.cc:3060] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660 [term 0 FOLLOWER]: Advancing to term 1
12:12:28.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.866501 26174 raft_consensus.cc:2468] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate be3b11c04416466d80c93fe84138e9f3 in term 1.
12:12:28.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.867750 26250 leader_election.cc:304] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [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: 3e5b992f190644e9948a82d48d3a5e9e, be3b11c04416466d80c93fe84138e9f3; no voters: 
12:12:28.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.868621 26540 raft_consensus.cc:2804] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 1 FOLLOWER]: Leader election won for term 1
12:12:28.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.871130 26540 raft_consensus.cc:697] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [term 1 LEADER]: Becoming Leader. State: Replica: be3b11c04416466d80c93fe84138e9f3, State: Running, Role: LEADER
12:12:28.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.872316 26540 consensus_queue.cc:237] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [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: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } }
12:12:28.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.872952 26453 raft_consensus.cc:2468] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate be3b11c04416466d80c93fe84138e9f3 in term 1.
12:12:28.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:28.877916 26503 tablet.cc:2406] T f5a989bef8304e709e26e3741fd3f112 P 554126118c88472eab1e5f18b063f660: Can't schedule compaction. Clean time has not been advanced past its initial value.
12:12:28.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:28.888594 25986 catalog_manager.cc:5697] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 reported cstate change: term changed from 0 to 1, leader changed from <none> to be3b11c04416466d80c93fe84138e9f3 (127.25.46.130). New cstate: current_term: 1 leader_uuid: "be3b11c04416466d80c93fe84138e9f3" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "be3b11c04416466d80c93fe84138e9f3" member_type: VOTER last_known_addr { host: "127.25.46.130" port: 33909 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 } health_report { overall_health: UNKNOWN } } }
12:12:28.967 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260708 12:12:28.966831 26229 tablet.cc:2406] T f5a989bef8304e709e26e3741fd3f112 P 3e5b992f190644e9948a82d48d3a5e9e: Can't schedule compaction. Clean time has not been advanced past its initial value.
12:12:29.327 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:2538) Learned about tablet f5a989bef8304e709e26e3741fd3f112 for table 'org.apache.kudu.client.TestHybridTime-1783512730557' with partition [<start>, <end>)
12:12:29.333 [DEBUG - kudu-nio-0] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=f5a989bef8304e709e26e3741fd3f112@[3e5b992f190644e9948a82d48d3a5e9e(127.25.46.129:38841),554126118c88472eab1e5f18b063f660(127.25.46.131:45131),be3b11c04416466d80c93fe84138e9f3(127.25.46.130:33909)[L]]}]
12:12:29.334 [DEBUG - kudu-nio-0] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=19), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.25.46.188:38273, [11ms] Sub RPC GetTableLocations: received response from server master-127.25.46.188:38273: OK, deferred=null) after lookup
12:12:29.344 [DEBUG - kudu-nio-0] (Connection.java:748) [peer be3b11c04416466d80c93fe84138e9f3(127.25.46.130:33909)] connecting to peer
12:12:29.347 [DEBUG - kudu-nio-3] (Connection.java:757) [peer be3b11c04416466d80c93fe84138e9f3(127.25.46.130:33909)] Successfully connected to peer
12:12:29.417 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:29.416538 26553 consensus_queue.cc:1048] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [LEADER]: Connected to new peer: Peer: permanent_uuid: "554126118c88472eab1e5f18b063f660" member_type: VOTER last_known_addr { host: "127.25.46.131" port: 45131 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
12:12:29.437 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x9471f519, L:/127.0.0.1:51244 - R:/127.25.46.130:33909] using TOKEN/null
12:12:29.466 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:29.466111 26553 consensus_queue.cc:1048] T f5a989bef8304e709e26e3741fd3f112 P be3b11c04416466d80c93fe84138e9f3 [LEADER]: Connected to new peer: Peer: permanent_uuid: "3e5b992f190644e9948a82d48d3a5e9e" member_type: VOTER last_known_addr { host: "127.25.46.129" port: 38841 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
12:12:29.552 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
12:12:29.572 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
12:12:29.588 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
12:12:29.592 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1082307960(state=PENDING, result=null, callback=<none>, errback=<none>)}
12:12:29.627 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
12:12:29.628 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@749231195(state=PENDING, result=null, callback=<none>, errback=<none>)}
12:12:29.644 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
12:12:29.644 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@518245272(state=PENDING, result=null, callback=<none>, errback=<none>)}
12:12:29.673 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
12:12:29.753 [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-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.775 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7305268242217598977) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.793 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7305268242217598981) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.809 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7305268242217598985) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.829 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7305268242217598989) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.850 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7305268242217598993) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.868 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7305268242217598996) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.883 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7305268242217598997) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783512730557, tablet=f5a989bef8304e709e26e3741fd3f112, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
12:12:29.885 [DEBUG - main] (Connection.java:575) [peer master-127.25.46.190:43805(127.25.46.190:43805)] disconnecting while in state READY
12:12:29.886 [DEBUG - main] (Connection.java:575) [peer master-127.25.46.188:38273(127.25.46.188:38273)] disconnecting while in state READY
12:12:29.887 [DEBUG - main] (Connection.java:575) [peer master-127.25.46.189:45923(127.25.46.189:45923)] disconnecting while in state READY
12:12:29.887 [DEBUG - main] (Connection.java:575) [peer be3b11c04416466d80c93fe84138e9f3(127.25.46.130:33909)] disconnecting while in state READY
12:12:29.892 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.25.46.189:45923(127.25.46.189:45923)] handling channelInactive
12:12:29.892 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.25.46.190:43805(127.25.46.190:43805)] handling channelInactive
12:12:29.892 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.25.46.190:43805(127.25.46.190:43805)] cleaning up while in state READY due to: connection closed
12:12:29.892 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.25.46.189:45923(127.25.46.189:45923)] cleaning up while in state READY due to: connection closed
12:12:29.893 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.25.46.188:38273(127.25.46.188:38273)] handling channelInactive
12:12:29.893 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.25.46.188:38273(127.25.46.188:38273)] cleaning up while in state READY due to: connection closed
12:12:29.894 [DEBUG - kudu-nio-3] (AsyncKuduClient.java:2704) Releasing all remaining resources
12:12:29.899 [DEBUG - kudu-nio-3] (Connection.java:239) [peer be3b11c04416466d80c93fe84138e9f3(127.25.46.130:33909)] handling channelInactive
12:12:29.899 [DEBUG - kudu-nio-3] (Connection.java:709) [peer be3b11c04416466d80c93fe84138e9f3(127.25.46.130:33909)] cleaning up while in state READY due to: connection closed
12:12:29.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:29.899576 25786 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu with pid 26072
12:12:29.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:29.937124 25786 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu with pid 26226
12:12:29.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:29.972393 25786 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu with pid 26368
12:12:30.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:30.002900 25786 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu with pid 25798
12:12:30.038 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:30.038365 25786 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu with pid 25868
12:12:30.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260708 12:12:30.068305 25786 external_mini_cluster.cc:1664] Killing /tmp/dist-test-taska0vbhI/build/tsan/bin/kudu with pid 25943
12:12:31.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-08T12:12:31Z chronyd exiting

Time: 21.691

OK (1 test)

