JUnit version 4.13.2
.23:51:31.410 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1783468291399
23:51:31.442 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
23:51:31.484 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
23:51:31.495 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-task9RMeFk/test-tmp
23:51:31.528 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/tsan/bin
23:51:31.532 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-task9RMeFk/java/build/dist-test/../../../build/tsan/bin/kudu, test, mini_cluster, --serialization=pb]
23:51:31.794 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

23:51:31.948 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
23:51:31.949 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

23:51:31.956 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:31.955758  6774 mini_oidc.cc:150] Starting JWKS server
23:51:31.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:31.960453  6774 webserver.cc:534] Webserver started at http://127.0.0.1:35707/ using document root <none> and password file <none>
23:51:31.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:31.961103  6774 mini_oidc.cc:186] Starting OIDC Discovery server
23:51:31.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:31.963383  6774 webserver.cc:534] Webserver started at http://127.0.0.1:43293/ using document root <none> and password file <none>
23:51:32.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:51:32Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
23:51:32.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:51:32Z Disabled control of system clock
23:51:32.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:32.027199  6774 external_mini_cluster.cc:1372] Running /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:32.028 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:32.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/wal
23:51:32.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data
23:51:32.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:51:32.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:51:32.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:51:32.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:32.031 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:32.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:32.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/logs
23:51:32.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data/info.pb
23:51:32.032 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:32.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:51:32.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:51:32.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:51:32.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:51:32.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:32.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:32.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:32.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:51:32.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:51:32.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:51:32.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:51:32.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.190:35015
23:51:32.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.190
23:51:32.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:32.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:32.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:32.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:32.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:32.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:51:32.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:32.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:51:32.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35707/jwks.json with env {}
23:51:32.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.357125  6787 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:51:32.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.357703  6787 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:51:32.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.358144  6787 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:51:32.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.390780  6787 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
23:51:32.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.391067  6787 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:51:32.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.391259  6787 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
23:51:32.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.391469  6787 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
23:51:32.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.391654  6787 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
23:51:32.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.391884  6787 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:35707/jwks.json
23:51:32.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:32.428552  6787 master_runner.cc:387] Master server non-default flags:
23:51:32.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:32.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:32.429 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:32.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:32.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data
23:51:32.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/wal
23:51:32.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:51:32.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:32.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:32.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.190:35015
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35707/jwks.json
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:32.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:32.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data/info.pb
23:51:32.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.190
23:51:32.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:32.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:51:32.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:32.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:51:32.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:51:32.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:32.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/logs
23:51:32.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on e7f111948823
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16836
23:51:32.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:51:32.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:32.430197  6787 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:51:32.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:32.432308  6787 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:51:32.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.446575  6794 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
23:51:33.705 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:32.446583  6793 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
23:51:33.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:33.708801  6796 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
23:51:33.715 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:33.715023  6787 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.267s	user 0.002s	sys 0.008s
23:51:33.716 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:33.715395  6787 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.268s	user 0.002s	sys 0.008s
23:51:33.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.716925  6787 server_base.cc:1061] running on GCE node
23:51:33.719 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.718672  6787 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:51:33.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.721880  6787 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:51:33.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.723357  6787 hybrid_clock.cc:648] HybridClock initialized: now 1783468293723287 us; error 95 us; skew 500 ppm
23:51:33.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.724398  6787 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:51:33.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.732371  6787 webserver.cc:534] Webserver started at http://127.6.157.190:35855/ using document root <none> and password file <none>
23:51:33.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.733466  6787 fs_manager.cc:362] Metadata directory not provided
23:51:33.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.733714  6787 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:51:33.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.734311  6787 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:51:33.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.738831  6787 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data/instance:
23:51:33.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eabe7c0dc5da4aaba5027cca5266bb28"
23:51:33.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:33 on dist-test-slave-nvb9"
23:51:33.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.740235  6787 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/wal/instance:
23:51:33.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eabe7c0dc5da4aaba5027cca5266bb28"
23:51:33.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:33 on dist-test-slave-nvb9"
23:51:33.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.748615  6787 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.006s	sys 0.001s
23:51:33.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.754961  6803 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:51:33.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.756361  6787 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.006s	sys 0.001s
23:51:33.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.756774  6787 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data,/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/wal
23:51:33.757 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "eabe7c0dc5da4aaba5027cca5266bb28"
23:51:33.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:33 on dist-test-slave-nvb9"
23:51:33.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.757309  6787 fs_report.cc:389] FS layout report
23:51:33.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:51:33.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/wal
23:51:33.759 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/wal
23:51:33.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data/data
23:51:33.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:51:33.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:51:33.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:51:33.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:51:33.763 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:51:33.763 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:51:33.763 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:51:33.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:51:33.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:51:33.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:51:33.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:51:33.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.834537  6787 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:51:33.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.836477  6787 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
23:51:33.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.837086  6787 kserver.cc:163] Server-wide thread pool size limit: 3276
23:51:33.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.925733  6787 rpc_server.cc:307] RPC server started. Bound to: 127.6.157.190:35015
23:51:33.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.925806  6854 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.157.190:35015 every 8 connection(s)
23:51:33.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.929237  6787 server_base.cc:1193] Dumped server information to /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/data/info.pb
23:51:33.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.935470  6855 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
23:51:33.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.937836  6774 external_mini_cluster.cc:1434] Started /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu as pid 6787
23:51:33.938 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.938361  6774 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-0/wal/instance
23:51:33.942 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.942301  6774 external_mini_cluster.cc:1372] Running /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:33.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:33.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/wal
23:51:33.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data
23:51:33.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:51:33.943 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:51:33.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:51:33.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:33.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:33.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:33.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/logs
23:51:33.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data/info.pb
23:51:33.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:33.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:51:33.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:51:33.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:51:33.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:51:33.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:33.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:33.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:33.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:51:33.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:51:33.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:51:33.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:51:33.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.189:35995
23:51:33.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.189
23:51:33.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:33.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:33.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:33.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:33.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:33.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:51:33.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:33.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:51:33.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35707/jwks.json with env {}
23:51:33.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.947378  6855 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } has no permanent_uuid. Determining permanent_uuid...
23:51:33.983 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:33.982328  6855 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } has no permanent_uuid. Determining permanent_uuid...
23:51:33.986 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:33.985589  6806 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:33.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:33.990962  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.189:35995: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:34.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.041388  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } attempt: 1
23:51:34.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.051342  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.189:35995: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:34.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.119853  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } attempt: 2
23:51:34.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.125084  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.189:35995: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:34.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.216464  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } attempt: 3
23:51:34.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.220916  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.189:35995: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:34.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.287372  6857 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:51:34.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.287920  6857 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:51:34.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.288493  6857 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:51:34.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.325690  6857 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
23:51:34.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.325979  6857 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:51:34.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.326280  6857 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
23:51:34.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.326598  6857 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
23:51:34.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.326925  6857 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
23:51:34.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.327636  6857 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:35707/jwks.json
23:51:34.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.364379  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } attempt: 4
23:51:34.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.369001  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.189:35995: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:34.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.374907  6857 master_runner.cc:387] Master server non-default flags:
23:51:34.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:34.375 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/wal
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:34.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:51:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.189:35995
23:51:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:51:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:51:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:51:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35707/jwks.json
23:51:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:34.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:34.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data/info.pb
23:51:34.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.189
23:51:34.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:34.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:51:34.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:34.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:51:34.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:51:34.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:34.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/logs
23:51:34.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:34.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:51:34.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:51:34.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:51:34.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:51:34.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:51:34.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:51:34.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on e7f111948823
23:51:34.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16836
23:51:34.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:51:34.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.376340  6857 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:51:34.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.378130  6857 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:51:34.391 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.390913  6866 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
23:51:34.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:34.668509  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } attempt: 5
23:51:34.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.677093  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.189:35995: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:35.226 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.224934  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } attempt: 6
23:51:35.238 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:35.237565  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.189:35995: Network error: Client connection negotiation failed: client connection to 127.6.157.189:35995: connect: Connection refused (error 111)
23:51:35.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:34.392916  6867 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
23:51:35.632 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:35.631508  6869 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
23:51:35.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:35.634539  6868 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1236 milliseconds
23:51:35.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.634677  6857 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
23:51:35.636 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.636087  6857 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:51:35.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.638968  6857 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:51:35.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.640393  6857 hybrid_clock.cc:648] HybridClock initialized: now 1783468295640333 us; error 65 us; skew 500 ppm
23:51:35.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.641366  6857 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:51:35.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.648180  6857 webserver.cc:534] Webserver started at http://127.6.157.189:35563/ using document root <none> and password file <none>
23:51:35.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.649281  6857 fs_manager.cc:362] Metadata directory not provided
23:51:35.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.649533  6857 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:51:35.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.650098  6857 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:51:35.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.654623  6857 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data/instance:
23:51:35.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5"
23:51:35.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:35 on dist-test-slave-nvb9"
23:51:35.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.656026  6857 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/wal/instance:
23:51:35.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5"
23:51:35.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:35 on dist-test-slave-nvb9"
23:51:35.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.664122  6857 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.001s
23:51:35.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.670414  6878 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:51:35.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.671586  6857 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.003s
23:51:35.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.672019  6857 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data,/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/wal
23:51:35.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5"
23:51:35.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:35 on dist-test-slave-nvb9"
23:51:35.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.672505  6857 fs_report.cc:389] FS layout report
23:51:35.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:51:35.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/wal
23:51:35.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/wal
23:51:35.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data/data
23:51:35.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:51:35.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:51:35.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:51:35.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:51:35.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:51:35.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:51:35.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:51:35.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:51:35.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:51:35.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:51:35.675 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:51:35.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.724543  6857 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:51:35.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.726519  6857 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
23:51:35.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.727134  6857 kserver.cc:163] Server-wide thread pool size limit: 3276
23:51:35.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.817401  6857 rpc_server.cc:307] RPC server started. Bound to: 127.6.157.189:35995
23:51:35.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.817502  6929 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.157.189:35995 every 8 connection(s)
23:51:35.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.820555  6857 server_base.cc:1193] Dumped server information to /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/data/info.pb
23:51:35.833 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.826175  6774 external_mini_cluster.cc:1434] Started /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu as pid 6857
23:51:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.826695  6774 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-1/wal/instance
23:51:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.828878  6774 external_mini_cluster.cc:1372] Running /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/wal
23:51:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data
23:51:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:51:35.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:51:35.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:51:35.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:35.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:35.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:35.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/logs
23:51:35.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data/info.pb
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:35.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.188:46275
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.188
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:35.837 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:35.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:35.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:35.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:35.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:51:35.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:35.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:51:35.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35707/jwks.json with env {}
23:51:35.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.829598  6930 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
23:51:35.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.847828  6930 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } has no permanent_uuid. Determining permanent_uuid...
23:51:35.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.875258  6930 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } has no permanent_uuid. Determining permanent_uuid...
23:51:35.890 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.889806  6930 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } has no permanent_uuid. Determining permanent_uuid...
23:51:35.892 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:35.892484  6882 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:35.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:35.897207  6930 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:35.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:35.946803  6930 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 1
23:51:35.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:35.952044  6930 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.021 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.020541  6930 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 2
23:51:36.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.025256  6930 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.117 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.116930  6930 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 3
23:51:36.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.121824  6930 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.186786  6932 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:51:36.187 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.187525  6932 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:51:36.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.188349  6932 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:51:36.221 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.221115  6932 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
23:51:36.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.221504  6932 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:51:36.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.221845  6932 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
23:51:36.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.222203  6932 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
23:51:36.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.222618  6932 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
23:51:36.223 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.222918  6932 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:35707/jwks.json
23:51:36.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.259379  6932 master_runner.cc:387] Master server non-default flags:
23:51:36.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:36.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:36.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:36.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/wal
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
23:51:36.261 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.188:46275
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:35707/jwks.json
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data/info.pb
23:51:36.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.188
23:51:36.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:36.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:51:36.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:36.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:51:36.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:51:36.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:36.263 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/logs
23:51:36.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:36.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:51:36.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:51:36.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
23:51:36.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:51:36.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:51:36.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:51:36.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on e7f111948823
23:51:36.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16836
23:51:36.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:51:36.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.261222  6932 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:51:36.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.263656  6932 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:51:36.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.265241  6930 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 4
23:51:36.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.270591  6930 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.278470  6942 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
23:51:36.299 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.298170  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } attempt: 7
23:51:36.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.345005  6855 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } has no permanent_uuid. Determining permanent_uuid...
23:51:36.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.367589  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.427 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.426209  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 1
23:51:36.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.446301  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.529 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.528122  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 2
23:51:36.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.537497  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.570134  6930 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 5
23:51:36.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.579836  6930 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.623113  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 3
23:51:36.634 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.633451  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:36.775 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:36.774111  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 4
23:51:36.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.798736  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:37.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.082346  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 5
23:51:37.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:37.092147  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:37.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.128127  6930 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 6
23:51:37.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:37.154666  6930 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:37.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:36.278621  6943 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
23:51:37.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:37.638875  6944 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1353 milliseconds
23:51:37.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:37.639462  6932 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.360s	user 0.004s	sys 0.004s
23:51:37.640 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:37.639894  6932 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.361s	user 0.004s	sys 0.004s
23:51:37.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:37.641613  6945 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
23:51:37.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.641718  6932 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
23:51:37.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.643415  6932 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:51:37.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.644732  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 6
23:51:37.647 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.646976  6932 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:51:37.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.648764  6932 hybrid_clock.cc:648] HybridClock initialized: now 1783468297648668 us; error 92 us; skew 500 ppm
23:51:37.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.650381  6932 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:51:37.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:37.655215  6855 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.157.188:46275: Network error: Client connection negotiation failed: client connection to 127.6.157.188:46275: connect: Connection refused (error 111)
23:51:37.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.660356  6932 webserver.cc:534] Webserver started at http://127.6.157.188:36417/ using document root <none> and password file <none>
23:51:37.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.661932  6932 fs_manager.cc:362] Metadata directory not provided
23:51:37.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.662288  6932 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:51:37.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.662941  6932 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:51:37.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.670529  6932 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data/instance:
23:51:37.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "18ea923058404259b2481265e67bb361"
23:51:37.671 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:37 on dist-test-slave-nvb9"
23:51:37.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.672930  6932 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/wal/instance:
23:51:37.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "18ea923058404259b2481265e67bb361"
23:51:37.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:37 on dist-test-slave-nvb9"
23:51:37.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.682807  6932 fs_manager.cc:696] Time spent creating directory manager: real 0.009s	user 0.008s	sys 0.000s
23:51:37.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.690714  6959 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:51:37.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.692152  6932 fs_manager.cc:730] Time spent opening block manager: real 0.005s	user 0.006s	sys 0.000s
23:51:37.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.692622  6932 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data,/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/wal
23:51:37.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "18ea923058404259b2481265e67bb361"
23:51:37.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:37 on dist-test-slave-nvb9"
23:51:37.693 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.693132  6932 fs_report.cc:389] FS layout report
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/wal
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/wal
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data/data
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:51:37.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:51:37.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:51:37.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:51:37.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:51:37.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:51:37.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:51:37.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:51:37.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:51:37.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.776664  6932 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:51:37.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.778384  6932 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
23:51:37.779 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.778942  6932 kserver.cc:163] Server-wide thread pool size limit: 3276
23:51:37.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.868722  6932 rpc_server.cc:307] RPC server started. Bound to: 127.6.157.188:46275
23:51:37.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.868870  7010 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.157.188:46275 every 8 connection(s)
23:51:37.872 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.872401  6932 server_base.cc:1193] Dumped server information to /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/data/info.pb
23:51:37.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.879205  7011 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
23:51:37.879 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.879582  6774 external_mini_cluster.cc:1434] Started /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu as pid 6932
23:51:37.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.879940  6774 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/master-2/wal/instance
23:51:37.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.896029  7011 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } has no permanent_uuid. Determining permanent_uuid...
23:51:37.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.928612  7011 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } has no permanent_uuid. Determining permanent_uuid...
23:51:37.940 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.939867  7011 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } has no permanent_uuid. Determining permanent_uuid...
23:51:37.958 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.957841  7011 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361: Bootstrap starting.
23:51:37.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.965865  7011 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361: Neither blocks nor log segments found. Creating new log.
23:51:37.968 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.968276  7011 log.cc:826] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361: Log is configured to *not* fsync() on all Append() calls
23:51:37.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.973158  7011 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361: No bootstrap required, opened a new log
23:51:37.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.994513  7011 raft_consensus.cc:359] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:37.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.995154  7011 raft_consensus.cc:385] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:51:37.995 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.995343  7011 raft_consensus.cc:740] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 18ea923058404259b2481265e67bb361, State: Initialized, Role: FOLLOWER
23:51:37.996 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.996186  7011 consensus_queue.cc:260] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [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: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:37.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.998672  7020 sys_catalog.cc:455] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [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: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:37.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:37.999388  7020 sys_catalog.cc:458] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: This master's current role is: FOLLOWER
23:51:38.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.000880  7011 sys_catalog.cc:565] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: configured and running, proceeding with master startup.
23:51:38.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:38.029618  7031 catalog_manager.cc:1593] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
23:51:38.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:38.030071  7031 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
23:51:38.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:38.034819  6965 tablet.cc:2406] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:51:38.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.024518  6932 master_runner.cc:187] Error getting master registration for 127.6.157.190:35015: OK, instance_id { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" instance_seqno: 1783468293902505 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
23:51:38.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.049662  6932 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
23:51:38.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.215291  6930 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 7
23:51:38.239 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.238926  6930 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: Bootstrap starting.
23:51:38.247 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.246901  6930 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: Neither blocks nor log segments found. Creating new log.
23:51:38.249 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.249364  6930 log.cc:826] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: Log is configured to *not* fsync() on all Append() calls
23:51:38.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.256218  6930 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: No bootstrap required, opened a new log
23:51:38.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.274799  7020 raft_consensus.cc:493] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
23:51:38.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.275444  7020 raft_consensus.cc:515] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:38.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.280723  7020 leader_election.cc:290] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers eabe7c0dc5da4aaba5027cca5266bb28 (127.6.157.190:35015), b5ca131b27bc4b4782ff4c08fd6db8b5 (127.6.157.189:35995)
23:51:38.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.286372  6930 raft_consensus.cc:359] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:38.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.287245  6930 raft_consensus.cc:385] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:51:38.287 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.287536  6930 raft_consensus.cc:740] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: b5ca131b27bc4b4782ff4c08fd6db8b5, State: Initialized, Role: FOLLOWER
23:51:38.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.288852  6930 consensus_queue.cc:260] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [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: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:38.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.291683  7035 sys_catalog.cc:455] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [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: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:38.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.292621  7035 sys_catalog.cc:458] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [sys.catalog]: This master's current role is: FOLLOWER
23:51:38.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.293581  6930 sys_catalog.cc:565] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [sys.catalog]: configured and running, proceeding with master startup.
23:51:38.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.293797  6905 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "18ea923058404259b2481265e67bb361" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" is_pre_election: true
23:51:38.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.294692  6905 raft_consensus.cc:2468] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 18ea923058404259b2481265e67bb361 in term 0.
23:51:38.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.296239  6962 leader_election.cc:304] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [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: 18ea923058404259b2481265e67bb361, b5ca131b27bc4b4782ff4c08fd6db8b5; no voters: 
23:51:38.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.297612  7020 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Leader pre-election won for term 1
23:51:38.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.298247  7020 raft_consensus.cc:493] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
23:51:38.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.298612  7020 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 0 FOLLOWER]: Advancing to term 1
23:51:38.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.307440  7020 raft_consensus.cc:515] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:38.313 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.312515  7020 leader_election.cc:290] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [CANDIDATE]: Term 1 election: Requested vote from peers eabe7c0dc5da4aaba5027cca5266bb28 (127.6.157.190:35015), b5ca131b27bc4b4782ff4c08fd6db8b5 (127.6.157.189:35995)
23:51:38.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.316357  6905 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "18ea923058404259b2481265e67bb361" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5"
23:51:38.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.316962  6905 raft_consensus.cc:3060] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [term 0 FOLLOWER]: Advancing to term 1
23:51:38.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.327602  6905 raft_consensus.cc:2468] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 18ea923058404259b2481265e67bb361 in term 1.
23:51:38.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.331421  6962 leader_election.cc:304] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [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: 18ea923058404259b2481265e67bb361, b5ca131b27bc4b4782ff4c08fd6db8b5; no voters: 
23:51:38.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.332413  7020 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 1 FOLLOWER]: Leader election won for term 1
23:51:38.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.334436  7020 raft_consensus.cc:697] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [term 1 LEADER]: Becoming Leader. State: Replica: 18ea923058404259b2481265e67bb361, State: Running, Role: LEADER
23:51:38.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.335482  7020 consensus_queue.cc:237] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [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: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:38.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.357513  7041 sys_catalog.cc:455] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 18ea923058404259b2481265e67bb361. Latest consensus state: current_term: 1 leader_uuid: "18ea923058404259b2481265e67bb361" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:38.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.358328  7041 sys_catalog.cc:458] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: This master's current role is: LEADER
23:51:38.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.367725  7047 catalog_manager.cc:1510] Loading table and tablet metadata into memory...
23:51:38.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.377746  7047 catalog_manager.cc:1519] Initializing Kudu cluster ID...
23:51:38.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:38.424329  7049 catalog_manager.cc:1593] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
23:51:38.425 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:38.424753  7049 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
23:51:38.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.393324  6857 master_runner.cc:187] Error getting master registration for 127.6.157.190:35015: OK, instance_id { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" instance_seqno: 1783468293902505 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
23:51:38.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.440575  6857 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
23:51:38.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.444484  6905 raft_consensus.cc:1275] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [term 1 FOLLOWER]: Refusing update from remote peer 18ea923058404259b2481265e67bb361: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:51:38.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.447067  7020 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [LEADER]: Connected to new peer: Peer: permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
23:51:38.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:38.487718  6884 tablet.cc:2406] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:51:38.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.489796  7035 sys_catalog.cc:455] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 18ea923058404259b2481265e67bb361. Latest consensus state: current_term: 1 leader_uuid: "18ea923058404259b2481265e67bb361" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:38.506 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.500171  7035 sys_catalog.cc:458] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [sys.catalog]: This master's current role is: FOLLOWER
23:51:38.518 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.512780  7052 mvcc.cc:204] Tried to move back new op lower bound from 7305086150358503424 to 7305086149995151360. Current Snapshot: MvccSnapshot[applied={T|T < 7305086150358503424}]
23:51:38.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.522691  7035 sys_catalog.cc:455] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "18ea923058404259b2481265e67bb361" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:38.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.523665  7035 sys_catalog.cc:458] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5 [sys.catalog]: This master's current role is: FOLLOWER
23:51:38.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.528707  7047 catalog_manager.cc:1382] Generated new cluster ID: 388b6dbd7de34c46a682d4f8f2860f1b
23:51:38.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.529024  7047 catalog_manager.cc:1530] Initializing Kudu internal certificate authority...
23:51:38.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.546540  7041 sys_catalog.cc:455] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "18ea923058404259b2481265e67bb361" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:38.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.547411  7041 sys_catalog.cc:458] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: This master's current role is: LEADER
23:51:38.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.567662  7047 catalog_manager.cc:1405] Generated new certificate authority record
23:51:38.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.574570  7047 catalog_manager.cc:1539] Loading token signing keys...
23:51:38.610 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.608938  7047 catalog_manager.cc:6069] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361: Generated new TSK 0
23:51:38.611 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.611503  7047 catalog_manager.cc:1549] Initializing in-progress tserver states...
23:51:38.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.658267  6774 external_mini_cluster.cc:1372] Running /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:38.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:38.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/wal
23:51:38.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data
23:51:38.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:51:38.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:51:38.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:51:38.659 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/logs
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data/info.pb
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:51:38.660 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.129:0
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.157.129
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.129
23:51:38.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:38.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
23:51:38.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.688668  6855 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } attempt: 7
23:51:38.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.740741  6855 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: Bootstrap starting.
23:51:38.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.773327  6855 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: Neither blocks nor log segments found. Creating new log.
23:51:38.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.783087  6855 log.cc:826] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: Log is configured to *not* fsync() on all Append() calls
23:51:38.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.816118  6855 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: No bootstrap required, opened a new log
23:51:38.961 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.948460  6855 raft_consensus.cc:359] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:38.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.963088  6855 raft_consensus.cc:385] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:51:38.972 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.967953  6855 raft_consensus.cc:740] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: eabe7c0dc5da4aaba5027cca5266bb28, State: Initialized, Role: FOLLOWER
23:51:38.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:38.981798  6855 consensus_queue.cc:260] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [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: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } }
23:51:39.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.023313  7060 sys_catalog.cc:455] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [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: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:39.033 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.031435  7060 sys_catalog.cc:458] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [sys.catalog]: This master's current role is: FOLLOWER
23:51:39.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.035442  6829 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "18ea923058404259b2481265e67bb361" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "eabe7c0dc5da4aaba5027cca5266bb28"
23:51:39.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.036358  6829 raft_consensus.cc:3060] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [term 0 FOLLOWER]: Advancing to term 1
23:51:39.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.039109  6830 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "18ea923058404259b2481265e67bb361" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" is_pre_election: true
23:51:39.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.051234  6829 raft_consensus.cc:2468] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 18ea923058404259b2481265e67bb361 in term 1.
23:51:39.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.053670  6828 raft_consensus.cc:1275] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [term 1 FOLLOWER]: Refusing update from remote peer 18ea923058404259b2481265e67bb361: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:51:39.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.058616  7041 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [LEADER]: Connected to new peer: Peer: permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
23:51:39.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.052522  6855 sys_catalog.cc:565] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [sys.catalog]: configured and running, proceeding with master startup.
23:51:39.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.099117  6809 tablet.cc:2406] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:51:39.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.149276  7067 sys_catalog.cc:455] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "18ea923058404259b2481265e67bb361" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:39.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.150164  7067 sys_catalog.cc:458] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [sys.catalog]: This master's current role is: FOLLOWER
23:51:39.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.147904  7060 sys_catalog.cc:455] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 18ea923058404259b2481265e67bb361. Latest consensus state: current_term: 1 leader_uuid: "18ea923058404259b2481265e67bb361" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:39.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.151014  7060 sys_catalog.cc:458] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28 [sys.catalog]: This master's current role is: FOLLOWER
23:51:39.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.179708  7076 catalog_manager.cc:805] Waiting for catalog manager background task thread to start: Service unavailable: Catalog manager is not initialized. State: Starting
23:51:39.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.251500  7057 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:51:39.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.252321  7057 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:51:39.253 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.253044  7057 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:51:39.289 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.288687  7057 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:51:39.290 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.289947  7057 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.6.157.129
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.333465  7057 tablet_server_runner.cc:78] Tablet server non-default flags:
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/wal
23:51:39.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.129:0
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data/info.pb
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.129
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:51:39.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.157.129
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/logs
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:51:39.336 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:51:39.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:51:39.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:51:39.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on e7f111948823
23:51:39.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16836
23:51:39.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:51:39.337 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.335479  7057 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:51:39.338 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.338130  7057 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:51:39.354 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.353641  7082 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
23:51:39.430 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.430262  7049 catalog_manager.cc:1294] Loaded cluster ID: 388b6dbd7de34c46a682d4f8f2860f1b
23:51:39.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.431818  7049 catalog_manager.cc:1587] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: loading cluster ID for follower catalog manager: success
23:51:39.445 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.444578  7049 catalog_manager.cc:1609] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: acquiring CA information for follower catalog manager: success
23:51:39.451 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.451503  7049 catalog_manager.cc:1637] T 00000000000000000000000000000000 P b5ca131b27bc4b4782ff4c08fd6db8b5: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
23:51:39.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.496881  7041 sys_catalog.cc:455] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "18ea923058404259b2481265e67bb361" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "eabe7c0dc5da4aaba5027cca5266bb28" member_type: VOTER last_known_addr { host: "127.6.157.190" port: 35015 } } peers { permanent_uuid: "b5ca131b27bc4b4782ff4c08fd6db8b5" member_type: VOTER last_known_addr { host: "127.6.157.189" port: 35995 } } peers { permanent_uuid: "18ea923058404259b2481265e67bb361" member_type: VOTER last_known_addr { host: "127.6.157.188" port: 46275 } } }
23:51:39.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:39.498476  7041 sys_catalog.cc:458] T 00000000000000000000000000000000 P 18ea923058404259b2481265e67bb361 [sys.catalog]: This master's current role is: LEADER
23:51:40.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:40.202986  7076 catalog_manager.cc:1294] Loaded cluster ID: 388b6dbd7de34c46a682d4f8f2860f1b
23:51:40.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:40.203451  7076 catalog_manager.cc:1587] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: loading cluster ID for follower catalog manager: success
23:51:40.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:40.224341  7076 catalog_manager.cc:1609] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: acquiring CA information for follower catalog manager: success
23:51:40.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:40.242597  7076 catalog_manager.cc:1637] T 00000000000000000000000000000000 P eabe7c0dc5da4aaba5027cca5266bb28: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
23:51:41.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:39.358247  7083 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
23:51:41.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:40.756114  7081 debug-util.cc:398] Leaking SignalData structure 0x7b0800028000 after lost signal to thread 7057
23:51:41.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:41.488147  7081 kernel_stack_watchdog.cc:198] Thread 7057 stuck at /home/jenkins-slave/workspace/build_and_test@2/src/kudu/util/thread.cc:642 for 395ms:
23:51:41.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
23:51:41.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
23:51:41.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
23:51:41.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
23:51:41.494 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:41.494261  7057 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 2.136s	user 0.682s	sys 1.216s
23:51:41.495 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:41.495522  7084 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 2135 milliseconds
23:51:41.496 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:41.495870  7057 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 2.138s	user 0.682s	sys 1.217s
23:51:41.497 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:41.496713  7085 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
23:51:41.500 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.500512  7057 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
23:51:41.502 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.502234  7057 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:51:41.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.516847  7057 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:51:41.519 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.518803  7057 hybrid_clock.cc:648] HybridClock initialized: now 1783468301518762 us; error 59 us; skew 500 ppm
23:51:41.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.520365  7057 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:51:41.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.561046  7057 webserver.cc:534] Webserver started at http://127.6.157.129:41665/ using document root <none> and password file <none>
23:51:41.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.562830  7057 fs_manager.cc:362] Metadata directory not provided
23:51:41.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.563445  7057 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:51:41.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.564272  7057 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:51:41.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.571053  7057 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data/instance:
23:51:41.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d6cbd3d6a62e4821aadbb16399b12108"
23:51:41.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:41 on dist-test-slave-nvb9"
23:51:41.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.573204  7057 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/wal/instance:
23:51:41.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d6cbd3d6a62e4821aadbb16399b12108"
23:51:41.573 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:41 on dist-test-slave-nvb9"
23:51:41.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.594420  7057 fs_manager.cc:696] Time spent creating directory manager: real 0.020s	user 0.011s	sys 0.007s
23:51:41.613 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.613268  7095 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:51:41.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.616958  7057 fs_manager.cc:730] Time spent opening block manager: real 0.017s	user 0.008s	sys 0.007s
23:51:41.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.617794  7057 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data,/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/wal
23:51:41.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d6cbd3d6a62e4821aadbb16399b12108"
23:51:41.618 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:41 on dist-test-slave-nvb9"
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.618662  7057 fs_report.cc:389] FS layout report
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/wal
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/wal
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data/data
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:51:41.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:51:41.620 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:51:41.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.750375  7057 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:51:41.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.753120  7057 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
23:51:41.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.754140  7057 kserver.cc:163] Server-wide thread pool size limit: 3276
23:51:41.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.764138  7057 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
23:51:41.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.781643  7057 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:51:41.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.782444  7057 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.001s	user 0.001s	sys 0.001s
23:51:41.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.783056  7057 ts_tablet_manager.cc:616] Registered 0 tablets
23:51:41.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:41.783514  7057 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
23:51:42.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.211000  7057 rpc_server.cc:307] RPC server started. Bound to: 127.6.157.129:40551
23:51:42.212 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.211179  7208 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.157.129:40551 every 8 connection(s)
23:51:42.214 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.214656  7057 server_base.cc:1193] Dumped server information to /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/data/info.pb
23:51:42.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.223933  6774 external_mini_cluster.cc:1434] Started /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu as pid 7057
23:51:42.228 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.224840  6774 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-0/wal/instance
23:51:42.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.241828  6774 external_mini_cluster.cc:1372] Running /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:42.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:42.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/wal
23:51:42.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data
23:51:42.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:51:42.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:51:42.242 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/logs
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data/info.pb
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:42.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.130:0
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.157.130
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.130
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:42.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
23:51:42.314 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.314355  7210 heartbeater.cc:344] Connected to a master server at 127.6.157.189:35995
23:51:42.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.314908  7210 heartbeater.cc:461] Registering TS with master...
23:51:42.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.321244  7210 heartbeater.cc:507] Master 127.6.157.189:35995 requested a full tablet report, sending...
23:51:42.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.323387  7209 heartbeater.cc:344] Connected to a master server at 127.6.157.188:46275
23:51:42.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.323767  7209 heartbeater.cc:461] Registering TS with master...
23:51:42.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.328931  6895 ts_manager.cc:194] Registered new tserver with Master: d6cbd3d6a62e4821aadbb16399b12108 (127.6.157.129:40551)
23:51:42.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.330391  7209 heartbeater.cc:507] Master 127.6.157.188:46275 requested a full tablet report, sending...
23:51:42.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.341830  6976 ts_manager.cc:194] Registered new tserver with Master: d6cbd3d6a62e4821aadbb16399b12108 (127.6.157.129:40551)
23:51:42.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.346283  7214 heartbeater.cc:344] Connected to a master server at 127.6.157.190:35015
23:51:42.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.346690  7214 heartbeater.cc:461] Registering TS with master...
23:51:42.347 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.347636  7214 heartbeater.cc:507] Master 127.6.157.190:35015 requested a full tablet report, sending...
23:51:42.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.348975  6976 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.6.157.129:49289
23:51:42.376 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.376083  6819 ts_manager.cc:194] Registered new tserver with Master: d6cbd3d6a62e4821aadbb16399b12108 (127.6.157.129:40551)
23:51:42.764 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:42.764351  7213 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:51:42.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:42.764823  7213 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:51:42.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:42.765223  7213 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:51:42.798 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:42.797859  7213 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:51:42.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:42.798877  7213 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.6.157.130
23:51:42.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.837831  7213 tablet_server_runner.cc:78] Tablet server non-default flags:
23:51:42.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:42.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:42.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:42.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:42.838 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/wal
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.130:0
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data/info.pb
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.130
23:51:42.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:42.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:42.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:51:42.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:42.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.157.130
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/logs
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:51:42.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on e7f111948823
23:51:42.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16836
23:51:42.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:51:42.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.839635  7213 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:51:42.847 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:42.841949  7213 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:51:42.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:42.859140  7226 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
23:51:43.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:43.355638  7209 heartbeater.cc:499] Master 127.6.157.188:46275 was elected leader, sending a full tablet report...
23:51:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:44.260601  7225 debug-util.cc:398] Leaking SignalData structure 0x7b0800028000 after lost signal to thread 7213
23:51:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:44.354516  7225 kernel_stack_watchdog.cc:198] Thread 7213 stuck at /home/jenkins-slave/workspace/build_and_test@2/src/kudu/util/thread.cc:642 for 393ms:
23:51:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
23:51:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
23:51:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
23:51:44.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
23:51:44.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:42.862903  7227 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
23:51:44.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:44.357285  7213 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 1.493s	user 0.000s	sys 0.002s
23:51:44.358 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:44.357625  7213 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 1.494s	user 0.000s	sys 0.002s
23:51:44.364 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:44.363862  7229 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
23:51:44.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.364305  7213 server_base.cc:1061] running on GCE node
23:51:44.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.365628  7213 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:51:44.368 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.368391  7213 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:51:44.370 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.370020  7213 hybrid_clock.cc:648] HybridClock initialized: now 1783468304369983 us; error 43 us; skew 500 ppm
23:51:44.371 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.370901  7213 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:51:44.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.378434  7213 webserver.cc:534] Webserver started at http://127.6.157.130:37921/ using document root <none> and password file <none>
23:51:44.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.379451  7213 fs_manager.cc:362] Metadata directory not provided
23:51:44.379 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.379731  7213 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:51:44.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.380337  7213 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:51:44.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.384795  7213 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data/instance:
23:51:44.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "053d81df053d4bb498a1e40d0e43094e"
23:51:44.385 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:44 on dist-test-slave-nvb9"
23:51:44.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.386220  7213 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/wal/instance:
23:51:44.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "053d81df053d4bb498a1e40d0e43094e"
23:51:44.386 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:44 on dist-test-slave-nvb9"
23:51:44.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.393715  7213 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.008s	sys 0.000s
23:51:44.399 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.399549  7242 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:51:44.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.400698  7213 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
23:51:44.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.401060  7213 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data,/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/wal
23:51:44.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "053d81df053d4bb498a1e40d0e43094e"
23:51:44.401 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:44 on dist-test-slave-nvb9"
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.401402  7213 fs_report.cc:389] FS layout report
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/wal
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/wal
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data/data
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:51:44.402 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:51:44.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:51:44.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:51:44.403 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:51:44.462 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.462085  7213 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:51:44.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.463819  7213 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
23:51:44.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.464380  7213 kserver.cc:163] Server-wide thread pool size limit: 3276
23:51:44.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.467382  7213 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
23:51:44.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.471946  7213 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:51:44.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.472189  7213 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:51:44.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.472537  7213 ts_tablet_manager.cc:616] Registered 0 tablets
23:51:44.473 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.472750  7213 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
23:51:44.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.621836  7213 rpc_server.cc:307] RPC server started. Bound to: 127.6.157.130:41451
23:51:44.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.621950  7354 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.157.130:41451 every 8 connection(s)
23:51:44.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.624706  7213 server_base.cc:1193] Dumped server information to /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/data/info.pb
23:51:44.636 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.632335  6774 external_mini_cluster.cc:1434] Started /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu as pid 7213
23:51:44.636 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.632799  6774 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-1/wal/instance
23:51:44.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.652390  6774 external_mini_cluster.cc:1372] Running /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:44.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu
23:51:44.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/wal
23:51:44.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data
23:51:44.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
23:51:44.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/logs
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data/info.pb
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.131:0
23:51:44.655 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.157.131
23:51:44.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.131
23:51:44.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:44.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:44.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:44.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:44.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:44.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
23:51:44.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.673130  7355 heartbeater.cc:344] Connected to a master server at 127.6.157.188:46275
23:51:44.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.673902  7356 heartbeater.cc:344] Connected to a master server at 127.6.157.189:35995
23:51:44.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.674068  7355 heartbeater.cc:461] Registering TS with master...
23:51:44.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.674244  7356 heartbeater.cc:461] Registering TS with master...
23:51:44.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.675765  7355 heartbeater.cc:507] Master 127.6.157.188:46275 requested a full tablet report, sending...
23:51:44.678 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.677186  7356 heartbeater.cc:507] Master 127.6.157.189:35995 requested a full tablet report, sending...
23:51:44.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.678982  6976 ts_manager.cc:194] Registered new tserver with Master: 053d81df053d4bb498a1e40d0e43094e (127.6.157.130:41451)
23:51:44.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.680433  6895 ts_manager.cc:194] Registered new tserver with Master: 053d81df053d4bb498a1e40d0e43094e (127.6.157.130:41451)
23:51:44.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.680748  6976 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.6.157.130:33667
23:51:44.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.683243  7357 heartbeater.cc:344] Connected to a master server at 127.6.157.190:35015
23:51:44.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.683578  7357 heartbeater.cc:461] Registering TS with master...
23:51:44.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.684266  7357 heartbeater.cc:507] Master 127.6.157.190:35015 requested a full tablet report, sending...
23:51:44.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:44.687186  6819 ts_manager.cc:194] Registered new tserver with Master: 053d81df053d4bb498a1e40d0e43094e (127.6.157.130:41451)
23:51:45.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:45.005400  7365 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
23:51:45.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:45.005972  7365 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
23:51:45.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:45.006534  7365 flags.cc:432] Enabled unsafe flag: --never_fsync=true
23:51:45.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:45.039175  7365 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
23:51:45.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:45.040155  7365 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.6.157.131
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:45.076707  7365 tablet_server_runner.cc:78] Tablet server non-default flags:
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.157.148:43223
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/wal
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.157.131:0
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
23:51:45.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data/info.pb
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.157.131
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.157.190:35015,127.6.157.189:35995,127.6.157.188:46275
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.157.131
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/logs
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 66915500c9e327897c442aea7b3936de4ff5c319
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
23:51:45.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 07 Jul 2026 23:36:41 UTC on e7f111948823
23:51:45.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 16836
23:51:45.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
23:51:45.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:45.078562  7365 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
23:51:45.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:45.080407  7365 file_cache.cc:492] Constructed file cache file cache with capacity 419430
23:51:45.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:45.095160  7371 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
23:51:45.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:45.685138  7355 heartbeater.cc:499] Master 127.6.157.188:46275 was elected leader, sending a full tablet report...
23:51:46.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:45.096037  7372 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
23:51:46.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:46.439085  7374 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
23:51:46.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:46.441831  7373 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1342 milliseconds
23:51:46.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.441951  7365 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
23:51:46.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.443380  7365 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
23:51:46.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.446492  7365 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
23:51:46.448 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.447974  7365 hybrid_clock.cc:648] HybridClock initialized: now 1783468306447927 us; error 61 us; skew 500 ppm
23:51:46.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.448874  7365 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
23:51:46.456 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.456346  7365 webserver.cc:534] Webserver started at http://127.6.157.131:35245/ using document root <none> and password file <none>
23:51:46.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.457293  7365 fs_manager.cc:362] Metadata directory not provided
23:51:46.457 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.457513  7365 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
23:51:46.458 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.457947  7365 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
23:51:46.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.462618  7365 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data/instance:
23:51:46.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8d5e5d8143b24544b83376469b9bd857"
23:51:46.463 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:46 on dist-test-slave-nvb9"
23:51:46.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.463896  7365 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/wal/instance:
23:51:46.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8d5e5d8143b24544b83376469b9bd857"
23:51:46.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:46 on dist-test-slave-nvb9"
23:51:46.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.471648  7365 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.006s	sys 0.001s
23:51:46.478 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.477937  7384 log_block_manager.cc:4031] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
23:51:46.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.479322  7365 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.005s	sys 0.000s
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.479701  7365 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data,/tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/wal
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "8d5e5d8143b24544b83376469b9bd857"
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-07-07 23:51:46 on dist-test-slave-nvb9"
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.480036  7365 fs_report.cc:389] FS layout report
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/wal
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/wal
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data/data
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
23:51:46.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
23:51:46.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
23:51:46.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
23:51:46.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
23:51:46.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
23:51:46.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
23:51:46.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
23:51:46.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.542039  7365 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
23:51:46.543 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.543615  7365 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
23:51:46.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.544025  7365 kserver.cc:163] Server-wide thread pool size limit: 3276
23:51:46.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.547343  7365 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
23:51:46.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.551657  7365 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
23:51:46.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.551846  7365 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
23:51:46.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.552040  7365 ts_tablet_manager.cc:616] Registered 0 tablets
23:51:46.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.552357  7365 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.001s	sys 0.000s
23:51:46.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.710181  7365 rpc_server.cc:307] RPC server started. Bound to: 127.6.157.131:45667
23:51:46.710 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.710414  7496 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.157.131:45667 every 8 connection(s)
23:51:46.713 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.713193  7365 server_base.cc:1193] Dumped server information to /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/data/info.pb
23:51:46.725 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.723222  6774 external_mini_cluster.cc:1434] Started /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu as pid 7365
23:51:46.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.723711  6774 external_mini_cluster.cc:1448] Reading /tmp/dist-test-task9RMeFk/test-tmp/mini-kudu-cluster5037566536272694433/ts-2/wal/instance
23:51:46.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.746271  7497 heartbeater.cc:344] Connected to a master server at 127.6.157.188:46275
23:51:46.747 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.746893  7497 heartbeater.cc:461] Registering TS with master...
23:51:46.749 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.748353  7497 heartbeater.cc:507] Master 127.6.157.188:46275 requested a full tablet report, sending...
23:51:46.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.750378  7499 heartbeater.cc:344] Connected to a master server at 127.6.157.190:35015
23:51:46.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.750699  7499 heartbeater.cc:461] Registering TS with master...
23:51:46.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.751214  6976 ts_manager.cc:194] Registered new tserver with Master: 8d5e5d8143b24544b83376469b9bd857 (127.6.157.131:45667)
23:51:46.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.751672  7499 heartbeater.cc:507] Master 127.6.157.190:35015 requested a full tablet report, sending...
23:51:46.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.752205  7498 heartbeater.cc:344] Connected to a master server at 127.6.157.189:35995
23:51:46.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.752570  7498 heartbeater.cc:461] Registering TS with master...
23:51:46.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.753114  6976 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.6.157.131:40885
23:51:46.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.753376  7498 heartbeater.cc:507] Master 127.6.157.189:35995 requested a full tablet report, sending...
23:51:46.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.755591  6819 ts_manager.cc:194] Registered new tserver with Master: 8d5e5d8143b24544b83376469b9bd857 (127.6.157.131:45667)
23:51:46.756 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.756004  6895 ts_manager.cc:194] Registered new tserver with Master: 8d5e5d8143b24544b83376469b9bd857 (127.6.157.131:45667)
23:51:46.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:46.769632  6774 external_mini_cluster.cc:955] 3 TS(s) registered with all masters
23:51:46.781 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
23:51:46.783 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

23:51:46.793 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.6.157.190"
      port: 35015
    }
    bound_http_address {
      host: "127.6.157.190"
      port: 35855
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.6.157.189"
      port: 35995
    }
    bound_http_address {
      host: "127.6.157.189"
      port: 35563
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.6.157.188"
      port: 46275
    }
    bound_http_address {
      host: "127.6.157.188"
      port: 36417
    }
  }
}

23:51:46.797 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_tservers {
}

23:51:46.800 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.6.157.129"
      port: 40551
    }
    bound_http_address {
      host: "127.6.157.129"
      port: 41665
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.6.157.130"
      port: 41451
    }
    bound_http_address {
      host: "127.6.157.130"
      port: 37921
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.6.157.131"
      port: 45667
    }
    bound_http_address {
      host: "127.6.157.131"
      port: 35245
    }
  }
}

23:51:46.800 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
23:51:46.990 [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)
23:51:47.047 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
23:51:47.205 [DEBUG - main] (Connection.java:748) [peer master-127.6.157.189:35995(127.6.157.189:35995)] connecting to peer
23:51:47.265 [DEBUG - main] (Connection.java:748) [peer master-127.6.157.190:35015(127.6.157.190:35015)] connecting to peer
23:51:47.269 [DEBUG - main] (Connection.java:748) [peer master-127.6.157.188:46275(127.6.157.188:46275)] connecting to peer
23:51:47.289 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.6.157.189:35995(127.6.157.189:35995)] Successfully connected to peer
23:51:47.292 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.6.157.188:46275(127.6.157.188:46275)] Successfully connected to peer
23:51:47.293 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.6.157.190:35015(127.6.157.190:35015)] Successfully connected to peer
23:51:47.395 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.6.157.188
23:51:47.396 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.6.157.190
23:51:47.397 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.6.157.189
23:51:47.629 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xefc4d6e6, L:/127.0.0.1:42808 - R:/127.6.157.190:35015] using SASL/PLAIN
23:51:47.630 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0x180267eb, L:/127.0.0.1:51512 - R:/127.6.157.188:46275] using SASL/PLAIN
23:51:47.630 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xb9b416f6, L:/127.0.0.1:53086 - R:/127.6.157.189:35995] using SASL/PLAIN
23:51:47.671 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
23:51:47.672 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=508), Traces: [0ms] refreshing cache from master, [25ms] Sub RPC ConnectToMaster: sending RPC to server master-127.6.157.189:35995, [97ms] Sub RPC ConnectToMaster: sending RPC to server master-127.6.157.190:35015, [100ms] Sub RPC ConnectToMaster: sending RPC to server master-127.6.157.188:46275, [476ms] Sub RPC ConnectToMaster: received response from server master-127.6.157.190:35015: OK, [476ms] Sub RPC ConnectToMaster: received response from server master-127.6.157.189:35995: OK, [477ms] Sub RPC ConnectToMaster: received response from server master-127.6.157.188:46275: OK, deferred=null) after lookup
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.734145  6976 catalog_manager.cc:2282] Servicing CreateTable request from {username='slave'} at 127.0.0.1:51512:
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1783468291258"
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
23:51:47.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
23:51:47.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
23:51:47.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:47.737495  6976 catalog_manager.cc:7076] 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-1783468291258 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
23:51:47.760 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.756845  7497 heartbeater.cc:499] Master 127.6.157.188:46275 was elected leader, sending a full tablet report...
23:51:47.811 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:894) Opened table cc9bddebe2494030b196a3fb2a8ffdfe
23:51:47.864 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.863523  7432 tablet_service.cc:1511] Processing CreateTablet for tablet e8578220e7e04c22b482da347ce730c2 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783468291258 [id=cc9bddebe2494030b196a3fb2a8ffdfe]), partition=RANGE (key) PARTITION UNBOUNDED
23:51:47.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.866008  7432 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet e8578220e7e04c22b482da347ce730c2. 1 dirs total, 0 dirs full, 0 dirs failed
23:51:47.894 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.893225  7290 tablet_service.cc:1511] Processing CreateTablet for tablet e8578220e7e04c22b482da347ce730c2 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783468291258 [id=cc9bddebe2494030b196a3fb2a8ffdfe]), partition=RANGE (key) PARTITION UNBOUNDED
23:51:47.897 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.884056  7144 tablet_service.cc:1511] Processing CreateTablet for tablet e8578220e7e04c22b482da347ce730c2 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1783468291258 [id=cc9bddebe2494030b196a3fb2a8ffdfe]), partition=RANGE (key) PARTITION UNBOUNDED
23:51:47.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.898280  7290 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet e8578220e7e04c22b482da347ce730c2. 1 dirs total, 0 dirs full, 0 dirs failed
23:51:47.898 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.898280  7144 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet e8578220e7e04c22b482da347ce730c2. 1 dirs total, 0 dirs full, 0 dirs failed
23:51:47.926 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.925853  7531 tablet_bootstrap.cc:492] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857: Bootstrap starting.
23:51:47.949 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.949151  7531 tablet_bootstrap.cc:654] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857: Neither blocks nor log segments found. Creating new log.
23:51:47.952 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.951937  7531 log.cc:826] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857: Log is configured to *not* fsync() on all Append() calls
23:51:47.973 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.973219  7531 tablet_bootstrap.cc:492] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857: No bootstrap required, opened a new log
23:51:47.974 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.973752  7531 ts_tablet_manager.cc:1403] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857: Time spent bootstrapping tablet: real 0.049s	user 0.007s	sys 0.009s
23:51:47.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.980549  7534 tablet_bootstrap.cc:492] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e: Bootstrap starting.
23:51:47.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.980907  7532 tablet_bootstrap.cc:492] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108: Bootstrap starting.
23:51:47.989 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.988780  7534 tablet_bootstrap.cc:654] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e: Neither blocks nor log segments found. Creating new log.
23:51:47.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.991338  7534 log.cc:826] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e: Log is configured to *not* fsync() on all Append() calls
23:51:47.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:47.999545  7532 tablet_bootstrap.cc:654] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108: Neither blocks nor log segments found. Creating new log.
23:51:48.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.001904  7532 log.cc:826] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108: Log is configured to *not* fsync() on all Append() calls
23:51:48.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.003485  7534 tablet_bootstrap.cc:492] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e: No bootstrap required, opened a new log
23:51:48.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.004366  7534 ts_tablet_manager.cc:1403] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e: Time spent bootstrapping tablet: real 0.024s	user 0.014s	sys 0.008s
23:51:48.006 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.005981  7531 raft_consensus.cc:359] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.006871  7531 raft_consensus.cc:385] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:51:48.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.007184  7531 raft_consensus.cc:740] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 8d5e5d8143b24544b83376469b9bd857, State: Initialized, Role: FOLLOWER
23:51:48.007 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.007459  7532 tablet_bootstrap.cc:492] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108: No bootstrap required, opened a new log
23:51:48.008 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.007969  7532 ts_tablet_manager.cc:1403] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108: Time spent bootstrapping tablet: real 0.028s	user 0.010s	sys 0.004s
23:51:48.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.008605  7531 consensus_queue.cc:260] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [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: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.020 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.019670  7531 ts_tablet_manager.cc:1434] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857: Time spent starting tablet: real 0.046s	user 0.026s	sys 0.014s
23:51:48.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.033054  7534 raft_consensus.cc:359] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.034407  7534 raft_consensus.cc:385] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:51:48.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.034916  7534 raft_consensus.cc:740] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 053d81df053d4bb498a1e40d0e43094e, State: Initialized, Role: FOLLOWER
23:51:48.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.036091  7534 consensus_queue.cc:260] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [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: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.039904  7534 ts_tablet_manager.cc:1434] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e: Time spent starting tablet: real 0.035s	user 0.028s	sys 0.004s
23:51:48.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.039317  7532 raft_consensus.cc:359] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.040 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.040226  7532 raft_consensus.cc:385] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
23:51:48.042 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.040578  7532 raft_consensus.cc:740] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d6cbd3d6a62e4821aadbb16399b12108, State: Initialized, Role: FOLLOWER
23:51:48.044 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.043637  7532 consensus_queue.cc:260] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [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: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.048200  7532 ts_tablet_manager.cc:1434] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108: Time spent starting tablet: real 0.040s	user 0.028s	sys 0.010s
23:51:48.084 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.084390  7538 raft_consensus.cc:493] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
23:51:48.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.084879  7538 raft_consensus.cc:515] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.088 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.087603  7538 leader_election.cc:290] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 8d5e5d8143b24544b83376469b9bd857 (127.6.157.131:45667), d6cbd3d6a62e4821aadbb16399b12108 (127.6.157.129:40551)
23:51:48.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.100864  7452 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "e8578220e7e04c22b482da347ce730c2" candidate_uuid: "053d81df053d4bb498a1e40d0e43094e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "8d5e5d8143b24544b83376469b9bd857" is_pre_election: true
23:51:48.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.101861  7452 raft_consensus.cc:2468] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 053d81df053d4bb498a1e40d0e43094e in term 0.
23:51:48.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.103426  7245 leader_election.cc:304] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [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: 053d81df053d4bb498a1e40d0e43094e, 8d5e5d8143b24544b83376469b9bd857; no voters: 
23:51:48.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.104336  7538 raft_consensus.cc:2804] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Leader pre-election won for term 1
23:51:48.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.104403  7164 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "e8578220e7e04c22b482da347ce730c2" candidate_uuid: "053d81df053d4bb498a1e40d0e43094e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d6cbd3d6a62e4821aadbb16399b12108" is_pre_election: true
23:51:48.105 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.104714  7538 raft_consensus.cc:493] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
23:51:48.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.105059  7538 raft_consensus.cc:3060] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 0 FOLLOWER]: Advancing to term 1
23:51:48.106 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.105269  7164 raft_consensus.cc:2468] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 053d81df053d4bb498a1e40d0e43094e in term 0.
23:51:48.111 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.110941  7538 raft_consensus.cc:515] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.112588  7538 leader_election.cc:290] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [CANDIDATE]: Term 1 election: Requested vote from peers 8d5e5d8143b24544b83376469b9bd857 (127.6.157.131:45667), d6cbd3d6a62e4821aadbb16399b12108 (127.6.157.129:40551)
23:51:48.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.113298  7452 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "e8578220e7e04c22b482da347ce730c2" candidate_uuid: "053d81df053d4bb498a1e40d0e43094e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "8d5e5d8143b24544b83376469b9bd857"
23:51:48.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.113405  7164 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "e8578220e7e04c22b482da347ce730c2" candidate_uuid: "053d81df053d4bb498a1e40d0e43094e" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d6cbd3d6a62e4821aadbb16399b12108"
23:51:48.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.113762  7164 raft_consensus.cc:3060] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [term 0 FOLLOWER]: Advancing to term 1
23:51:48.114 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.113797  7452 raft_consensus.cc:3060] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [term 0 FOLLOWER]: Advancing to term 1
23:51:48.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.118412  7164 raft_consensus.cc:2468] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 053d81df053d4bb498a1e40d0e43094e in term 1.
23:51:48.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.118865  7452 raft_consensus.cc:2468] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 053d81df053d4bb498a1e40d0e43094e in term 1.
23:51:48.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.119212  7245 leader_election.cc:304] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [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: 053d81df053d4bb498a1e40d0e43094e, d6cbd3d6a62e4821aadbb16399b12108; no voters: 
23:51:48.120 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.119957  7538 raft_consensus.cc:2804] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 1 FOLLOWER]: Leader election won for term 1
23:51:48.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.122282  7538 raft_consensus.cc:697] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [term 1 LEADER]: Becoming Leader. State: Replica: 053d81df053d4bb498a1e40d0e43094e, State: Running, Role: LEADER
23:51:48.125 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.124450  7538 consensus_queue.cc:237] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [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: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } }
23:51:48.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.137913  6975 catalog_manager.cc:5696] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e reported cstate change: term changed from 0 to 1, leader changed from <none> to 053d81df053d4bb498a1e40d0e43094e (127.6.157.130). New cstate: current_term: 1 leader_uuid: "053d81df053d4bb498a1e40d0e43094e" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "053d81df053d4bb498a1e40d0e43094e" member_type: VOTER last_known_addr { host: "127.6.157.130" port: 41451 } health_report { overall_health: HEALTHY } } }
23:51:48.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:48.156548  7362 tablet.cc:2406] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:51:48.220 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet e8578220e7e04c22b482da347ce730c2 for table 'org.apache.kudu.client.TestHybridTime-1783468291258' with partition [<start>, <end>)
23:51:48.227 [DEBUG - kudu-nio-2] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299993, tablet=e8578220e7e04c22b482da347ce730c2@[053d81df053d4bb498a1e40d0e43094e(127.6.157.130:41451)[L],8d5e5d8143b24544b83376469b9bd857(127.6.157.131:45667),d6cbd3d6a62e4821aadbb16399b12108(127.6.157.129:40551)]}]
23:51:48.229 [DEBUG - kudu-nio-2] (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.6.157.188:46275, [10ms] Sub RPC GetTableLocations: received response from server master-127.6.157.188:46275: OK, deferred=null) after lookup
23:51:48.231 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:48.231160  7504 tablet.cc:2406] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:51:48.240 [DEBUG - kudu-nio-2] (Connection.java:748) [peer 053d81df053d4bb498a1e40d0e43094e(127.6.157.130:41451)] connecting to peer
23:51:48.242 [DEBUG - kudu-nio-3] (Connection.java:757) [peer 053d81df053d4bb498a1e40d0e43094e(127.6.157.130:41451)] Successfully connected to peer
23:51:48.292 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260707 23:51:48.292096  7217 tablet.cc:2406] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108: Can't schedule compaction. Clean time has not been advanced past its initial value.
23:51:48.306 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0xd3c6617f, L:/127.0.0.1:51342 - R:/127.6.157.130:41451] using TOKEN/null
23:51:48.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.325595  7164 raft_consensus.cc:1275] T e8578220e7e04c22b482da347ce730c2 P d6cbd3d6a62e4821aadbb16399b12108 [term 1 FOLLOWER]: Refusing update from remote peer 053d81df053d4bb498a1e40d0e43094e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:51:48.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.326215  7452 raft_consensus.cc:1275] T e8578220e7e04c22b482da347ce730c2 P 8d5e5d8143b24544b83376469b9bd857 [term 1 FOLLOWER]: Refusing update from remote peer 053d81df053d4bb498a1e40d0e43094e: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
23:51:48.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.327517  7542 consensus_queue.cc:1048] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [LEADER]: Connected to new peer: Peer: permanent_uuid: "d6cbd3d6a62e4821aadbb16399b12108" member_type: VOTER last_known_addr { host: "127.6.157.129" port: 40551 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.001s
23:51:48.329 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.328570  7538 consensus_queue.cc:1048] T e8578220e7e04c22b482da347ce730c2 P 053d81df053d4bb498a1e40d0e43094e [LEADER]: Connected to new peer: Peer: permanent_uuid: "8d5e5d8143b24544b83376469b9bd857" member_type: VOTER last_known_addr { host: "127.6.157.131" port: 45667 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
23:51:48.355 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.352818  7547 mvcc.cc:204] Tried to move back new op lower bound from 7305086190881103872 to 7305086190085849088. Current Snapshot: MvccSnapshot[applied={T|T < 7305086190881103872}]
23:51:48.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.358717  7548 mvcc.cc:204] Tried to move back new op lower bound from 7305086190881103872 to 7305086190085849088. Current Snapshot: MvccSnapshot[applied={T|T < 7305086190881103872}]
23:51:48.447 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:51:48.483 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:51:48.500 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:51:48.505 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1172404532(state=PENDING, result=null, callback=<none>, errback=<none>)}
23:51:48.527 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:51:48.528 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@885536826(state=PENDING, result=null, callback=<none>, errback=<none>)}
23:51:48.553 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:51:48.553 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@2025296905(state=PENDING, result=null, callback=<none>, errback=<none>)}
23:51:48.575 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
23:51:48.626 [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-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.639 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7305086211361103873) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.653 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7305086211361103880) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.664 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7305086211361103884) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.677 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7305086211361103888) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.692 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7305086211361103892) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.705 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7305086211361103896) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.721 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7305086211361103897) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1783468291258, tablet=e8578220e7e04c22b482da347ce730c2, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
23:51:48.724 [DEBUG - main] (Connection.java:575) [peer master-127.6.157.190:35015(127.6.157.190:35015)] disconnecting while in state READY
23:51:48.725 [DEBUG - main] (Connection.java:575) [peer master-127.6.157.188:46275(127.6.157.188:46275)] disconnecting while in state READY
23:51:48.726 [DEBUG - main] (Connection.java:575) [peer 053d81df053d4bb498a1e40d0e43094e(127.6.157.130:41451)] disconnecting while in state READY
23:51:48.726 [DEBUG - main] (Connection.java:575) [peer master-127.6.157.189:35995(127.6.157.189:35995)] disconnecting while in state READY
23:51:48.731 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.6.157.189:35995(127.6.157.189:35995)] handling channelInactive
23:51:48.731 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.6.157.189:35995(127.6.157.189:35995)] cleaning up while in state READY due to: connection closed
23:51:48.732 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2704) Releasing all remaining resources
23:51:48.732 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.6.157.188:46275(127.6.157.188:46275)] handling channelInactive
23:51:48.732 [DEBUG - kudu-nio-3] (Connection.java:239) [peer 053d81df053d4bb498a1e40d0e43094e(127.6.157.130:41451)] handling channelInactive
23:51:48.732 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.6.157.188:46275(127.6.157.188:46275)] cleaning up while in state READY due to: connection closed
23:51:48.732 [DEBUG - kudu-nio-3] (Connection.java:709) [peer 053d81df053d4bb498a1e40d0e43094e(127.6.157.130:41451)] cleaning up while in state READY due to: connection closed
23:51:48.745 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.6.157.190:35015(127.6.157.190:35015)] handling channelInactive
23:51:48.746 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.6.157.190:35015(127.6.157.190:35015)] cleaning up while in state READY due to: connection closed
23:51:48.746 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.745904  6774 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu with pid 7057
23:51:48.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.784878  6774 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu with pid 7213
23:51:48.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.829118  6774 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu with pid 7365
23:51:48.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.859197  6774 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu with pid 6787
23:51:48.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.899914  6774 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu with pid 6857
23:51:48.937 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260707 23:51:48.933426  6774 external_mini_cluster.cc:1664] Killing /tmp/dist-test-task9RMeFk/build/tsan/bin/kudu with pid 6932
23:51:50.211 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-07-07T23:51:50Z chronyd exiting

Time: 19.864

OK (1 test)

