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

14:21:56.458 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:21:56.459 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

14:21:56.464 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.463655  5697 mini_oidc.cc:150] Starting JWKS server
14:21:56.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.467324  5697 webserver.cc:533] Webserver started at http://127.0.0.1:38173/ using document root <none> and password file <none>
14:21:56.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.467871  5697 mini_oidc.cc:186] Starting OIDC Discovery server
14:21:56.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.470397  5697 webserver.cc:533] Webserver started at http://127.0.0.1:40757/ using document root <none> and password file <none>
14:21:56.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:21:56Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
14:21:56.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:21:56Z Disabled control of system clock
14:21:56.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.532750  5697 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:21:56.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:21:56.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/wal
14:21:56.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data
14:21:56.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:21:56.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:21:56.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:21:56.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:21:56.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:21:56.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:21:56.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/logs
14:21:56.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data/info.pb
14:21:56.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:21:56.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:21:56.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:21:56.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:21:56.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:21:56.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:21:56.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:21:56.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:21:56.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:21:56.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:21:56.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:21:56.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:21:56.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.126:37643
14:21:56.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.126
14:21:56.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:21:56.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:21:56.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:21:56.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:21:56.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:21:56.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:21:56.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:21:56.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:21:56.542 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38173/jwks.json with env {}
14:21:56.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.839308  5709 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:21:56.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.839872  5709 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:21:56.841 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.840376  5709 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:21:56.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.872867  5709 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:21:56.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.873270  5709 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:21:56.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.873592  5709 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:21:56.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.873925  5709 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:21:56.874 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.874287  5709 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:21:56.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.874558  5709 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:38173/jwks.json
14:21:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.912253  5709 master_runner.cc:387] Master server non-default flags:
14:21:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:21:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:21:56.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:21:56.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:21:56.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data
14:21:56.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/wal
14:21:56.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:21:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:21:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:21:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:21:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:21:56.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.126:37643
14:21:56.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:21:56.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:21:56.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:21:56.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38173/jwks.json
14:21:56.916 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:21:56.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:21:56.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data/info.pb
14:21:56.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.126
14:21:56.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:21:56.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:21:56.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:21:56.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:21:56.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:21:56.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:21:56.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/logs
14:21:56.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:21:56.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:21:56.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:21:56.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:21:56.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:21:56.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:21:56.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:21:56.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:54 UTC on bdcb31816ec0
14:21:56.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15854
14:21:56.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:21:56.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.914042  5709 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:21:56.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:56.916510  5709 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:21:56.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.929637  5716 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:21:58.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.929771  5715 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:21:58.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:56.933866  5718 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:21:58.118 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.118302  5709 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:21:58.119 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.118865  5717 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
14:21:58.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.122841  5709 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:21:58.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.126124  5709 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:21:58.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.127552  5709 hybrid_clock.cc:648] HybridClock initialized: now 1779114118127506 us; error 62 us; skew 500 ppm
14:21:58.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.128425  5709 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:21:58.135 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.134763  5709 webserver.cc:533] Webserver started at http://127.5.144.126:43153/ using document root <none> and password file <none>
14:21:58.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.135777  5709 fs_manager.cc:362] Metadata directory not provided
14:21:58.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.135974  5709 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:21:58.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.136467  5709 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:21:58.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.141028  5709 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data/instance:
14:21:58.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0f64a36ee45a4860be85068e1b7098ea"
14:21:58.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:21:58 on dist-test-slave-tpwj"
14:21:58.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.142107  5709 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/wal/instance:
14:21:58.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0f64a36ee45a4860be85068e1b7098ea"
14:21:58.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:21:58 on dist-test-slave-tpwj"
14:21:58.150 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.150200  5709 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.009s	sys 0.000s
14:21:58.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.156209  5725 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:21:58.157 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.157409  5709 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.004s	sys 0.000s
14:21:58.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.157727  5709 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data,/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/wal
14:21:58.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "0f64a36ee45a4860be85068e1b7098ea"
14:21:58.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:21:58 on dist-test-slave-tpwj"
14:21:58.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.158021  5709 fs_report.cc:389] FS layout report
14:21:58.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:21:58.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/wal
14:21:58.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/wal
14:21:58.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data/data
14:21:58.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:21:58.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:21:58.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:21:58.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:21:58.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:21:58.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:21:58.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:21:58.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:21:58.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:21:58.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:21:58.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:21:58.243 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.242709  5709 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:21:58.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.244690  5709 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:21:58.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.245332  5709 kserver.cc:163] Server-wide thread pool size limit: 3276
14:21:58.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.319185  5709 rpc_server.cc:307] RPC server started. Bound to: 127.5.144.126:37643
14:21:58.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.319329  5776 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.144.126:37643 every 8 connection(s)
14:21:58.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.322292  5709 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/data/info.pb
14:21:58.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.323937  5697 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu as pid 5709
14:21:58.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.324468  5697 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-0/wal/instance
14:21:58.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.329905  5777 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:21:58.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.329782  5697 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:21:58.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:21:58.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/wal
14:21:58.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data
14:21:58.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:21:58.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:21:58.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:21:58.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:21:58.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:21:58.331 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:21:58.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/logs
14:21:58.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data/info.pb
14:21:58.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:21:58.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:21:58.332 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:21:58.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:21:58.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:21:58.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:21:58.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:21:58.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:21:58.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:21:58.333 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.125:42177
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.125
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:21:58.334 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:21:58.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:21:58.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:21:58.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:21:58.335 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38173/jwks.json with env {}
14:21:58.346 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.343935  5777 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } has no permanent_uuid. Determining permanent_uuid...
14:21:58.374 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.374176  5777 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } has no permanent_uuid. Determining permanent_uuid...
14:21:58.377 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.376642  5726 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:58.381 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.380789  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.125:42177: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:58.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.430459  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } attempt: 1
14:21:58.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.435683  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.125:42177: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:58.504 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.504117  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } attempt: 2
14:21:58.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.508224  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.125:42177: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:58.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.599639  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } attempt: 3
14:21:58.604 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.604020  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.125:42177: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:58.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.648388  5779 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:21:58.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.648928  5779 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:21:58.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.649401  5779 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:21:58.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.681913  5779 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:21:58.682 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.682211  5779 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:21:58.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.682404  5779 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:21:58.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.682585  5779 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:21:58.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.682762  5779 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:21:58.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.683017  5779 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:38173/jwks.json
14:21:58.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.733341  5779 master_runner.cc:387] Master server non-default flags:
14:21:58.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:21:58.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:21:58.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:21:58.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/wal
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:21:58.735 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.125:42177
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38173/jwks.json
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data/info.pb
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.125
14:21:58.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:21:58.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:21:58.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:21:58.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:21:58.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:21:58.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:21:58.737 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/logs
14:21:58.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:21:58.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:21:58.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:21:58.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:21:58.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:21:58.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:21:58.738 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:21:58.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:54 UTC on bdcb31816ec0
14:21:58.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15854
14:21:58.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:21:58.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.734915  5779 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:21:58.739 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.736883  5779 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:21:58.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.747489  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } attempt: 4
14:21:58.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.750891  5789 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:21:58.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.754081  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.125:42177: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:59.054 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.053664  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } attempt: 5
14:21:59.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:59.058701  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.125:42177: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:59.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.606371  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } attempt: 6
14:21:59.615 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:59.614763  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.125:42177: Network error: Client connection negotiation failed: client connection to 127.5.144.125:42177: connect: Connection refused (error 111)
14:21:59.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.753841  5788 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:21:59.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:21:58.754439  5791 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:21:59.963 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:58.754969  5779 server_base.cc:1061] running on GCE node
14:21:59.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.966377  5779 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:21:59.969 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.969270  5779 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:21:59.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.970662  5779 hybrid_clock.cc:648] HybridClock initialized: now 1779114119970639 us; error 50 us; skew 500 ppm
14:21:59.971 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.971673  5779 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:21:59.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.977837  5779 webserver.cc:533] Webserver started at http://127.5.144.125:40717/ using document root <none> and password file <none>
14:21:59.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.978731  5779 fs_manager.cc:362] Metadata directory not provided
14:21:59.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.978912  5779 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:21:59.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.979380  5779 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:21:59.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.983707  5779 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data/instance:
14:21:59.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d4b886028a4c41ffa0fe6f0693e54819"
14:21:59.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:21:59 on dist-test-slave-tpwj"
14:21:59.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.984865  5779 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/wal/instance:
14:21:59.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d4b886028a4c41ffa0fe6f0693e54819"
14:21:59.985 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:21:59 on dist-test-slave-tpwj"
14:21:59.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.992115  5779 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.007s	sys 0.001s
14:21:59.998 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.998073  5800 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:21:59.999 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.999457  5779 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.001s
14:22:00.000 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:21:59.999855  5779 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data,/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/wal
14:22:00.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d4b886028a4c41ffa0fe6f0693e54819"
14:22:00.001 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:21:59 on dist-test-slave-tpwj"
14:22:00.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.000322  5779 fs_report.cc:389] FS layout report
14:22:00.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:22:00.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/wal
14:22:00.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/wal
14:22:00.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data/data
14:22:00.002 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:22:00.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:22:00.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:22:00.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:22:00.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:22:00.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:22:00.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:22:00.003 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:22:00.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:22:00.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:22:00.004 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:22:00.051 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.051048  5779 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:22:00.052 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.052598  5779 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:22:00.053 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.053004  5779 kserver.cc:163] Server-wide thread pool size limit: 3276
14:22:00.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.125501  5779 rpc_server.cc:307] RPC server started. Bound to: 127.5.144.125:42177
14:22:00.126 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.125622  5851 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.144.125:42177 every 8 connection(s)
14:22:00.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.128639  5779 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/data/info.pb
14:22:00.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.130563  5697 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu as pid 5779
14:22:00.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.131027  5697 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-1/wal/instance
14:22:00.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.134865  5697 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:00.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:00.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/wal
14:22:00.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data
14:22:00.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:22:00.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/logs
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data/info.pb
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:00.138 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:22:00.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.124:38509
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.124
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:00.140 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:00.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:22:00.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:22:00.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:00.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:22:00.141 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38173/jwks.json with env {}
14:22:00.142 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.136518  5852 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:22:00.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.152102  5852 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } has no permanent_uuid. Determining permanent_uuid...
14:22:00.174 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.174088  5852 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } has no permanent_uuid. Determining permanent_uuid...
14:22:00.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.189018  5852 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } has no permanent_uuid. Determining permanent_uuid...
14:22:00.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.191666  5801 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.196142  5852 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.245651  5852 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 1
14:22:00.250 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.250100  5852 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.318534  5852 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 2
14:22:00.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.323717  5852 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.415 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.415220  5852 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 3
14:22:00.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.419739  5852 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.480943  5854 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:22:00.481 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.481639  5854 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:22:00.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.482304  5854 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:22:00.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.514436  5854 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:22:00.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.514848  5854 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:22:00.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.515168  5854 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:22:00.515 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.515542  5854 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:22:00.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.515911  5854 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:22:00.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.516192  5854 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:38173/jwks.json
14:22:00.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.552568  5854 master_runner.cc:387] Master server non-default flags:
14:22:00.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:00.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:00.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:00.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:22:00.553 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/wal
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.124:38509
14:22:00.554 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:22:00.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:22:00.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:22:00.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:38173/jwks.json
14:22:00.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:00.555 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:00.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data/info.pb
14:22:00.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.124
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/logs
14:22:00.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:00.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:22:00.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:22:00.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:22:00.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:22:00.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:22:00.558 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:22:00.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:54 UTC on bdcb31816ec0
14:22:00.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15854
14:22:00.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:22:00.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.554359  5854 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:22:00.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.556751  5854 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:22:00.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.563162  5852 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 4
14:22:00.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.568759  5852 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.569345  5864 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:00.676 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.675220  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } attempt: 7
14:22:00.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.688454  5777 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } has no permanent_uuid. Determining permanent_uuid...
14:22:00.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.694824  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.753504  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 1
14:22:00.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.758508  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.840032  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 2
14:22:00.845 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.844702  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.868304  5852 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 5
14:22:00.873 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.872915  5852 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:00.930 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:00.930183  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 3
14:22:00.935 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.935091  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:01.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.075634  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 4
14:22:01.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:01.080683  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:01.364 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.364120  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 5
14:22:01.369 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:01.368669  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:01.420 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.420327  5852 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 6
14:22:01.428 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:01.428225  5852 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:01.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.570694  5865 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:01.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:00.574448  5867 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:01.777 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:01.777613  5866 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Connection time-out
14:22:01.778 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.777773  5854 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:22:01.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.781934  5854 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:22:01.784 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.784551  5854 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:22:01.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.785954  5854 hybrid_clock.cc:648] HybridClock initialized: now 1779114121785922 us; error 41 us; skew 500 ppm
14:22:01.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.786839  5854 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:22:01.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.793221  5854 webserver.cc:533] Webserver started at http://127.5.144.124:42681/ using document root <none> and password file <none>
14:22:01.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.794292  5854 fs_manager.cc:362] Metadata directory not provided
14:22:01.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.794533  5854 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:22:01.795 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.795051  5854 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:22:01.799 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.799469  5854 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data/instance:
14:22:01.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "db0c8c40e2414436a510d6e69a967299"
14:22:01.800 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:01 on dist-test-slave-tpwj"
14:22:01.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.800720  5854 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/wal/instance:
14:22:01.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "db0c8c40e2414436a510d6e69a967299"
14:22:01.801 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:01 on dist-test-slave-tpwj"
14:22:01.808 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.807964  5854 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.007s	sys 0.000s
14:22:01.814 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.813827  5878 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:22:01.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.814955  5854 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.002s	sys 0.000s
14:22:01.815 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.815438  5854 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data,/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/wal
14:22:01.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "db0c8c40e2414436a510d6e69a967299"
14:22:01.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:01 on dist-test-slave-tpwj"
14:22:01.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.815904  5854 fs_report.cc:389] FS layout report
14:22:01.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:22:01.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/wal
14:22:01.816 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/wal
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data/data
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:22:01.817 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:22:01.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:22:01.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:22:01.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:22:01.818 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:22:01.869 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.869000  5854 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:22:01.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.870712  5854 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:22:01.871 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.871281  5854 kserver.cc:163] Server-wide thread pool size limit: 3276
14:22:01.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.921074  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 6
14:22:01.929 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:01.928439  5777 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.5.144.124:38509: Network error: Client connection negotiation failed: client connection to 127.5.144.124:38509: connect: Connection refused (error 111)
14:22:01.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.947501  5854 rpc_server.cc:307] RPC server started. Bound to: 127.5.144.124:38509
14:22:01.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.947557  5931 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.144.124:38509 every 8 connection(s)
14:22:01.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.950263  5854 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/data/info.pb
14:22:01.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.956710  5697 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu as pid 5854
14:22:01.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.957080  5932 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet 00000000000000000000000000000000. 1 dirs total, 0 dirs full, 0 dirs failed
14:22:01.957 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.957159  5697 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/master-2/wal/instance
14:22:01.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:01.975399  5932 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } has no permanent_uuid. Determining permanent_uuid...
14:22:02.005 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.004416  5932 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } has no permanent_uuid. Determining permanent_uuid...
14:22:02.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.014066  5932 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } has no permanent_uuid. Determining permanent_uuid...
14:22:02.030 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.030323  5932 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299: Bootstrap starting.
14:22:02.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.036757  5932 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299: Neither blocks nor log segments found. Creating new log.
14:22:02.039 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.038720  5932 log.cc:826] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299: Log is configured to *not* fsync() on all Append() calls
14:22:02.043 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.043190  5932 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299: No bootstrap required, opened a new log
14:22:02.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.062456  5932 raft_consensus.cc:359] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:02.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.063105  5932 raft_consensus.cc:385] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:22:02.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.063380  5932 raft_consensus.cc:740] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: db0c8c40e2414436a510d6e69a967299, State: Initialized, Role: FOLLOWER
14:22:02.064 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.064033  5932 consensus_queue.cc:260] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [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: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:02.066 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.066303  5941 sys_catalog.cc:455] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [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: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:02.067 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.066921  5941 sys_catalog.cc:458] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: This master's current role is: FOLLOWER
14:22:02.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.068148  5932 sys_catalog.cc:565] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: configured and running, proceeding with master startup.
14:22:02.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:02.093834  5952 catalog_manager.cc:1594] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:22:02.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:02.094189  5952 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:22:02.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.089178  5854 master_runner.cc:187] Error getting master registration for 127.5.144.126:37643: OK, instance_id { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" instance_seqno: 1779114118299369 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:22:02.112 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.112497  5854 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:22:02.127 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:02.126653  5884 tablet.cc:2404] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:22:02.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.322122  5941 raft_consensus.cc:493] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:22:02.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.322737  5941 raft_consensus.cc:515] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:02.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.326608  5941 leader_election.cc:290] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 0f64a36ee45a4860be85068e1b7098ea (127.5.144.126:37643), d4b886028a4c41ffa0fe6f0693e54819 (127.5.144.125:42177)
14:22:02.489 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.488647  5852 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 7
14:22:02.511 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.511387  5852 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: Bootstrap starting.
14:22:02.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.520465  5852 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: Neither blocks nor log segments found. Creating new log.
14:22:02.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.522733  5852 log.cc:826] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: Log is configured to *not* fsync() on all Append() calls
14:22:02.527 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.527457  5852 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: No bootstrap required, opened a new log
14:22:02.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.558220  5852 raft_consensus.cc:359] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:02.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.559688  5852 raft_consensus.cc:385] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:22:02.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.559984  5852 raft_consensus.cc:740] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d4b886028a4c41ffa0fe6f0693e54819, State: Initialized, Role: FOLLOWER
14:22:02.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.561098  5852 consensus_queue.cc:260] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [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: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:02.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.563984  5956 sys_catalog.cc:455] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [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: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:02.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.565003  5956 sys_catalog.cc:458] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [sys.catalog]: This master's current role is: FOLLOWER
14:22:02.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.565977  5852 sys_catalog.cc:565] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [sys.catalog]: configured and running, proceeding with master startup.
14:22:02.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.566210  5827 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "db0c8c40e2414436a510d6e69a967299" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d4b886028a4c41ffa0fe6f0693e54819" is_pre_election: true
14:22:02.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.567086  5827 raft_consensus.cc:2468] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate db0c8c40e2414436a510d6e69a967299 in term 0.
14:22:02.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.568755  5879 leader_election.cc:304] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [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: d4b886028a4c41ffa0fe6f0693e54819, db0c8c40e2414436a510d6e69a967299; no voters: 
14:22:02.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.569640  5941 raft_consensus.cc:2804] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Leader pre-election won for term 1
14:22:02.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.569995  5941 raft_consensus.cc:493] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:22:02.575 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.570279  5941 raft_consensus.cc:3060] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 0 FOLLOWER]: Advancing to term 1
14:22:02.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.578766  5941 raft_consensus.cc:515] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:02.588 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.587463  5827 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "db0c8c40e2414436a510d6e69a967299" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d4b886028a4c41ffa0fe6f0693e54819"
14:22:02.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.588033  5827 raft_consensus.cc:3060] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [term 0 FOLLOWER]: Advancing to term 1
14:22:02.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.596026  5827 raft_consensus.cc:2468] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate db0c8c40e2414436a510d6e69a967299 in term 1.
14:22:02.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.597201  5879 leader_election.cc:304] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [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: d4b886028a4c41ffa0fe6f0693e54819, db0c8c40e2414436a510d6e69a967299; no voters: 
14:22:02.602 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.601975  5941 leader_election.cc:290] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [CANDIDATE]: Term 1 election: Requested vote from peers 0f64a36ee45a4860be85068e1b7098ea (127.5.144.126:37643), d4b886028a4c41ffa0fe6f0693e54819 (127.5.144.125:42177)
14:22:02.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.603261  5941 raft_consensus.cc:2804] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 1 FOLLOWER]: Leader election won for term 1
14:22:02.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.604758  5941 raft_consensus.cc:697] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [term 1 LEADER]: Becoming Leader. State: Replica: db0c8c40e2414436a510d6e69a967299, State: Running, Role: LEADER
14:22:02.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.606411  5941 consensus_queue.cc:237] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [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: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:02.643 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:02.643100  5969 catalog_manager.cc:1594] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:22:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:02.643536  5969 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:22:02.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.643608  5964 sys_catalog.cc:455] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: SysCatalogTable state changed. Reason: New leader db0c8c40e2414436a510d6e69a967299. Latest consensus state: current_term: 1 leader_uuid: "db0c8c40e2414436a510d6e69a967299" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:02.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.648172  5964 sys_catalog.cc:458] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: This master's current role is: LEADER
14:22:02.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.662460  5971 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
14:22:02.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.672106  5971 catalog_manager.cc:1520] Initializing Kudu cluster ID...
14:22:02.699 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.633890  5779 master_runner.cc:187] Error getting master registration for 127.5.144.126:37643: OK, instance_id { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" instance_seqno: 1779114118299369 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:22:02.700 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.700181  5779 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:22:02.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.715644  5827 raft_consensus.cc:1275] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [term 1 FOLLOWER]: Refusing update from remote peer db0c8c40e2414436a510d6e69a967299: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:22:02.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.721957  5964 consensus_queue.cc:1048] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [LEADER]: Connected to new peer: Peer: permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:22:02.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.740006  5956 sys_catalog.cc:455] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [sys.catalog]: SysCatalogTable state changed. Reason: New leader db0c8c40e2414436a510d6e69a967299. Latest consensus state: current_term: 1 leader_uuid: "db0c8c40e2414436a510d6e69a967299" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:02.741 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.740751  5956 sys_catalog.cc:458] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [sys.catalog]: This master's current role is: FOLLOWER
14:22:02.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.749965  5972 mvcc.cc:204] Tried to move back new op lower bound from 7287251446594674688 to 7287251446218027008. Current Snapshot: MvccSnapshot[applied={T|T < 7287251446594674688}]
14:22:02.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.760798  5964 sys_catalog.cc:455] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "db0c8c40e2414436a510d6e69a967299" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:02.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.761567  5964 sys_catalog.cc:458] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: This master's current role is: LEADER
14:22:02.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.761186  5973 mvcc.cc:204] Tried to move back new op lower bound from 7287251446594674688 to 7287251446218027008. Current Snapshot: MvccSnapshot[applied={T|T < 7287251446594674688}]
14:22:02.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.763929  5956 sys_catalog.cc:455] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "db0c8c40e2414436a510d6e69a967299" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:02.765 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.764647  5956 sys_catalog.cc:458] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819 [sys.catalog]: This master's current role is: FOLLOWER
14:22:02.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.768828  5971 catalog_manager.cc:1383] Generated new cluster ID: bfc62bd80a204a2b8fe3929ddd952b2e
14:22:02.769 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.769141  5971 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
14:22:02.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.828554  5971 catalog_manager.cc:1406] Generated new certificate authority record
14:22:02.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.830737  5971 catalog_manager.cc:1540] Loading token signing keys...
14:22:02.858 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.858134  5971 catalog_manager.cc:6070] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299: Generated new TSK 0
14:22:02.860 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.859798  5971 catalog_manager.cc:1550] Initializing in-progress tserver states...
14:22:02.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.917141  5697 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:02.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/wal
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:02.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/logs
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data/info.pb
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:02.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.65:0
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.144.65
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.65
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:02.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:02.921 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:02.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:22:02.962 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:02.961925  5777 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } attempt: 7
14:22:03.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.035693  5777 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea: Bootstrap starting.
14:22:03.056 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.055648  5777 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea: Neither blocks nor log segments found. Creating new log.
14:22:03.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.058661  5777 log.cc:826] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea: Log is configured to *not* fsync() on all Append() calls
14:22:03.080 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.079998  5777 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea: No bootstrap required, opened a new log
14:22:03.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.136780  5777 raft_consensus.cc:359] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:03.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.151443  5777 raft_consensus.cc:385] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:22:03.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.152318  5777 raft_consensus.cc:740] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 0f64a36ee45a4860be85068e1b7098ea, State: Initialized, Role: FOLLOWER
14:22:03.154 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.153797  5777 consensus_queue.cc:260] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [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: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } }
14:22:03.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.164288  5980 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [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: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:03.167 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.167583  5980 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [sys.catalog]: This master's current role is: FOLLOWER
14:22:03.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.169895  5777 sys_catalog.cc:565] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [sys.catalog]: configured and running, proceeding with master startup.
14:22:03.182 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.170493  5750 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [term 0 FOLLOWER]: Advancing to term 1
14:22:03.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.185681  5750 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [term 1 FOLLOWER]: Refusing update from remote peer db0c8c40e2414436a510d6e69a967299: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:22:03.188 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.171672  5752 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "db0c8c40e2414436a510d6e69a967299" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "0f64a36ee45a4860be85068e1b7098ea" is_pre_election: true
14:22:03.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.171002  5751 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "db0c8c40e2414436a510d6e69a967299" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "0f64a36ee45a4860be85068e1b7098ea"
14:22:03.192 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.190511  5964 consensus_queue.cc:1048] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [LEADER]: Connected to new peer: Peer: permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:22:03.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.302027  5989 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "db0c8c40e2414436a510d6e69a967299" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:03.306 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.306169  5989 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [sys.catalog]: This master's current role is: FOLLOWER
14:22:03.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.305452  5980 sys_catalog.cc:455] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [sys.catalog]: SysCatalogTable state changed. Reason: New leader db0c8c40e2414436a510d6e69a967299. Latest consensus state: current_term: 1 leader_uuid: "db0c8c40e2414436a510d6e69a967299" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:03.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.307271  5980 sys_catalog.cc:458] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea [sys.catalog]: This master's current role is: FOLLOWER
14:22:03.318 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.318344  5996 catalog_manager.cc:805] Waiting for catalog manager background task thread to start: Service unavailable: Catalog manager is not initialized. State: Starting
14:22:03.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:03.471031  5977 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:22:03.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:03.471695  5977 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:22:03.472 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:03.472378  5977 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:22:03.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:03.507036  5977 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:22:03.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:03.508196  5977 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.5.144.65
14:22:03.547 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.547289  5977 tablet_server_runner.cc:78] Tablet server non-default flags:
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/wal
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.65:0
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:03.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data/info.pb
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.65
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.144.65
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/logs
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:22:03.549 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:54 UTC on bdcb31816ec0
14:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15854
14:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:22:03.550 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.549089  5977 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:22:03.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.551224  5977 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:22:03.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:03.566398  6002 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:03.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.651933  5969 catalog_manager.cc:1295] Loaded cluster ID: bfc62bd80a204a2b8fe3929ddd952b2e
14:22:03.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.653056  5969 catalog_manager.cc:1588] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: loading cluster ID for follower catalog manager: success
14:22:03.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.671386  5969 catalog_manager.cc:1610] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: acquiring CA information for follower catalog manager: success
14:22:03.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.682541  5969 catalog_manager.cc:1638] T 00000000000000000000000000000000 P d4b886028a4c41ffa0fe6f0693e54819: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:22:03.754 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.752941  5964 sys_catalog.cc:455] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "db0c8c40e2414436a510d6e69a967299" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "0f64a36ee45a4860be85068e1b7098ea" member_type: VOTER last_known_addr { host: "127.5.144.126" port: 37643 } } peers { permanent_uuid: "d4b886028a4c41ffa0fe6f0693e54819" member_type: VOTER last_known_addr { host: "127.5.144.125" port: 42177 } } peers { permanent_uuid: "db0c8c40e2414436a510d6e69a967299" member_type: VOTER last_known_addr { host: "127.5.144.124" port: 38509 } } }
14:22:03.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:03.754591  5964 sys_catalog.cc:458] T 00000000000000000000000000000000 P db0c8c40e2414436a510d6e69a967299 [sys.catalog]: This master's current role is: LEADER
14:22:04.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:04.365231  5996 catalog_manager.cc:1295] Loaded cluster ID: bfc62bd80a204a2b8fe3929ddd952b2e
14:22:04.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:04.366238  5996 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea: loading cluster ID for follower catalog manager: success
14:22:04.408 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:04.407286  5996 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea: acquiring CA information for follower catalog manager: success
14:22:04.423 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:04.422566  5996 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 0f64a36ee45a4860be85068e1b7098ea: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:22:05.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:04.968920  6001 debug-util.cc:398] Leaking SignalData structure 0x7b08000271c0 after lost signal to thread 5977
14:22:05.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:05.539304  6001 kernel_stack_watchdog.cc:198] Thread 5977 stuck at /home/jenkins-slave/workspace/build_and_test@2/src/kudu/util/thread.cc:642 for 397ms:
14:22:05.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Kernel stack:
14:22:05.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) (could not read kernel stack)
14:22:05.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) User stack:
14:22:05.540 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) <Timed out: thread did not respond: maybe it is blocking signals>
14:22:05.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:03.570574  6003 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:05.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:05.544926  5977 thread.cc:641] GCE (cloud detector) Time spent creating pthread: real 1.976s	user 0.647s	sys 1.051s
14:22:05.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:05.545297  5977 thread.cc:608] GCE (cloud detector) Time spent starting thread: real 1.977s	user 0.647s	sys 1.051s
14:22:05.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:05.552073  6010 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:05.556 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.555859  5977 server_base.cc:1061] running on GCE node
14:22:05.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.557258  5977 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:22:05.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.559926  5977 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:22:05.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.561442  5977 hybrid_clock.cc:648] HybridClock initialized: now 1779114125561405 us; error 42 us; skew 500 ppm
14:22:05.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.562311  5977 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:22:05.570 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.569897  5977 webserver.cc:533] Webserver started at http://127.5.144.65:40111/ using document root <none> and password file <none>
14:22:05.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.570920  5977 fs_manager.cc:362] Metadata directory not provided
14:22:05.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.571173  5977 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:22:05.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.572546  5977 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:22:05.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.577129  5977 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data/instance:
14:22:05.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d189881ba5a245f998149788c77b1805"
14:22:05.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:05 on dist-test-slave-tpwj"
14:22:05.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.578600  5977 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/wal/instance:
14:22:05.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d189881ba5a245f998149788c77b1805"
14:22:05.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:05 on dist-test-slave-tpwj"
14:22:05.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.587334  5977 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.007s	sys 0.000s
14:22:05.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.593755  6015 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:22:05.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.595088  5977 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.001s	sys 0.002s
14:22:05.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.595501  5977 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data,/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/wal
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "d189881ba5a245f998149788c77b1805"
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:05 on dist-test-slave-tpwj"
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.595906  5977 fs_report.cc:389] FS layout report
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/wal
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/wal
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data/data
14:22:05.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:22:05.597 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:22:05.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.683995  5977 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:22:05.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.685999  5977 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:22:05.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.686576  5977 kserver.cc:163] Server-wide thread pool size limit: 3276
14:22:05.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.690341  5977 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:22:05.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.696640  5977 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:22:05.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.696928  5977 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:22:05.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.697233  5977 ts_tablet_manager.cc:616] Registered 0 tablets
14:22:05.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.697451  5977 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:22:05.881 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.881449  5977 rpc_server.cc:307] RPC server started. Bound to: 127.5.144.65:45827
14:22:05.882 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.881595  6127 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.144.65:45827 every 8 connection(s)
14:22:05.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.885075  5977 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/data/info.pb
14:22:05.899 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.894037  5697 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu as pid 5977
14:22:05.900 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.894635  5697 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-0/wal/instance
14:22:05.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.912459  5697 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/wal
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/logs
14:22:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data/info.pb
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.66:0
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.144.66
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.66
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:05.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:05.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:22:05.978 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.978374  6132 heartbeater.cc:344] Connected to a master server at 127.5.144.126:37643
14:22:05.979 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.978895  6132 heartbeater.cc:461] Registering TS with master...
14:22:05.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.980194  6132 heartbeater.cc:507] Master 127.5.144.126:37643 requested a full tablet report, sending...
14:22:05.984 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.983691  5742 ts_manager.cc:194] Registered new tserver with Master: d189881ba5a245f998149788c77b1805 (127.5.144.65:45827)
14:22:05.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.986732  6128 heartbeater.cc:344] Connected to a master server at 127.5.144.124:38509
14:22:05.987 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.987079  6128 heartbeater.cc:461] Registering TS with master...
14:22:05.988 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.987986  6128 heartbeater.cc:507] Master 127.5.144.124:38509 requested a full tablet report, sending...
14:22:05.991 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.990481  5895 ts_manager.cc:194] Registered new tserver with Master: d189881ba5a245f998149788c77b1805 (127.5.144.65:45827)
14:22:05.993 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:05.993129  5895 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.5.144.65:42387
14:22:06.009 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.009341  6129 heartbeater.cc:344] Connected to a master server at 127.5.144.125:42177
14:22:06.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.009769  6129 heartbeater.cc:461] Registering TS with master...
14:22:06.010 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.010438  6129 heartbeater.cc:507] Master 127.5.144.125:42177 requested a full tablet report, sending...
14:22:06.014 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.013731  5817 ts_manager.cc:194] Registered new tserver with Master: d189881ba5a245f998149788c77b1805 (127.5.144.65:45827)
14:22:06.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:06.356060  6136 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:22:06.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:06.356549  6136 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:22:06.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:06.356945  6136 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:22:06.393 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:06.392900  6136 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:22:06.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:06.393779  6136 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.5.144.66
14:22:06.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.430775  6136 tablet_server_runner.cc:78] Tablet server non-default flags:
14:22:06.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:06.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:06.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:06.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:22:06.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data
14:22:06.431 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/wal
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.66:0
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data/info.pb
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.66
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:06.432 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.144.66
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/logs
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:22:06.433 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:22:06.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:22:06.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:22:06.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:54 UTC on bdcb31816ec0
14:22:06.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15854
14:22:06.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:22:06.434 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.432571  6136 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:22:06.435 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.434793  6136 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:22:06.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:06.449636  6145 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:06.997 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:06.997325  6128 heartbeater.cc:499] Master 127.5.144.124:38509 was elected leader, sending a full tablet report...
14:22:07.251 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:07.250224  5773 debug-util.cc:398] Leaking SignalData structure 0x7b080008d0c0 after lost signal to thread 5710
14:22:07.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:07.252365  5773 debug-util.cc:398] Leaking SignalData structure 0x7b08000a4120 after lost signal to thread 5776
14:22:08.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:07.853955  6144 debug-util.cc:398] Leaking SignalData structure 0x7b08000271c0 after lost signal to thread 6136
14:22:08.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:08.629702  6147 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 2178 milliseconds
14:22:08.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:06.451274  6146 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:08.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:08.628921  6136 thread.cc:641] OpenStack (cloud detector) Time spent creating pthread: real 2.179s	user 0.613s	sys 1.371s
14:22:08.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:08.630764  6136 thread.cc:608] OpenStack (cloud detector) Time spent starting thread: real 2.181s	user 0.614s	sys 1.372s
14:22:08.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:08.631461  6148 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:08.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.631369  6136 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:22:08.636 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.636315  6136 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:22:08.639 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.639204  6136 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:22:08.641 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.640758  6136 hybrid_clock.cc:648] HybridClock initialized: now 1779114128640610 us; error 149 us; skew 500 ppm
14:22:08.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.641893  6136 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:22:08.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.650774  6136 webserver.cc:533] Webserver started at http://127.5.144.66:43441/ using document root <none> and password file <none>
14:22:08.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.652185  6136 fs_manager.cc:362] Metadata directory not provided
14:22:08.652 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.652510  6136 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:22:08.653 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.653090  6136 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:22:08.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.660761  6136 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data/instance:
14:22:08.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5312fecf023a470f87a2f6f00a88f127"
14:22:08.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:08 on dist-test-slave-tpwj"
14:22:08.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.662500  6136 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/wal/instance:
14:22:08.662 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5312fecf023a470f87a2f6f00a88f127"
14:22:08.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:08 on dist-test-slave-tpwj"
14:22:08.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.673609  6136 fs_manager.cc:696] Time spent creating directory manager: real 0.010s	user 0.004s	sys 0.005s
14:22:08.681 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.681463  6157 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:22:08.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.682691  6136 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.006s	sys 0.000s
14:22:08.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.683084  6136 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data,/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/wal
14:22:08.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "5312fecf023a470f87a2f6f00a88f127"
14:22:08.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:08 on dist-test-slave-tpwj"
14:22:08.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.683461  6136 fs_report.cc:389] FS layout report
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/wal
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/wal
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data/data
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:22:08.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:22:08.781 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.781034  6136 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:22:08.782 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.782621  6136 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:22:08.783 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.783036  6136 kserver.cc:163] Server-wide thread pool size limit: 3276
14:22:08.786 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.786284  6136 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:22:08.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.791332  6136 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:22:08.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.791532  6136 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.001s	sys 0.000s
14:22:08.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.791802  6136 ts_tablet_manager.cc:616] Registered 0 tablets
14:22:08.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:08.791949  6136 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:22:09.024 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.024326  6136 rpc_server.cc:307] RPC server started. Bound to: 127.5.144.66:45527
14:22:09.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.024387  6269 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.144.66:45527 every 8 connection(s)
14:22:09.029 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.029208  6136 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/data/info.pb
14:22:09.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.032359  5697 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu as pid 6136
14:22:09.035 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.033430  5697 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-1/wal/instance
14:22:09.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.070492  5697 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:09.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu
14:22:09.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/wal
14:22:09.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data
14:22:09.071 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/logs
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data/info.pb
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:09.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.67:0
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.144.67
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.67
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:09.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:22:09.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.091151  6270 heartbeater.cc:344] Connected to a master server at 127.5.144.124:38509
14:22:09.094 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.091743  6270 heartbeater.cc:461] Registering TS with master...
14:22:09.095 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.093139  6270 heartbeater.cc:507] Master 127.5.144.124:38509 requested a full tablet report, sending...
14:22:09.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.096517  5895 ts_manager.cc:194] Registered new tserver with Master: 5312fecf023a470f87a2f6f00a88f127 (127.5.144.66:45527)
14:22:09.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.098239  5895 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.5.144.66:59267
14:22:09.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.108654  6271 heartbeater.cc:344] Connected to a master server at 127.5.144.125:42177
14:22:09.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.109051  6271 heartbeater.cc:461] Registering TS with master...
14:22:09.110 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.109786  6271 heartbeater.cc:507] Master 127.5.144.125:42177 requested a full tablet report, sending...
14:22:09.113 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.112986  5817 ts_manager.cc:194] Registered new tserver with Master: 5312fecf023a470f87a2f6f00a88f127 (127.5.144.66:45527)
14:22:09.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.122375  6274 heartbeater.cc:344] Connected to a master server at 127.5.144.126:37643
14:22:09.122 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.122681  6274 heartbeater.cc:461] Registering TS with master...
14:22:09.123 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.123255  6274 heartbeater.cc:507] Master 127.5.144.126:37643 requested a full tablet report, sending...
14:22:09.130 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.129667  5742 ts_manager.cc:194] Registered new tserver with Master: 5312fecf023a470f87a2f6f00a88f127 (127.5.144.66:45527)
14:22:09.657 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:09.657238  6278 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:22:09.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:09.657768  6278 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:22:09.658 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:09.658174  6278 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:22:09.694 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:09.693960  6278 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:22:09.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:09.694803  6278 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.5.144.67
14:22:09.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.732040  6278 tablet_server_runner.cc:78] Tablet server non-default flags:
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.5.144.84:35409
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/wal
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.5.144.67:0
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data/info.pb
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.5.144.67
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.5.144.126:37643,127.5.144.125:42177,127.5.144.124:38509
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:22:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.5.144.67
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/logs
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:54 UTC on bdcb31816ec0
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15854
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) TSAN enabled
14:22:09.734 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.733799  6278 env_posix.cc:2267] Not raising this process' open files per process limit of 1048576; it is already as high as it can go
14:22:09.736 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:09.735769  6278 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:22:09.762 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:09.761144  6285 instance_detector.cc:116] could not retrieve AWS instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:10.104 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:10.104106  6270 heartbeater.cc:499] Master 127.5.144.124:38509 was elected leader, sending a full tablet report...
14:22:11.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:09.764360  6286 instance_detector.cc:116] could not retrieve Azure instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:11.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:11.162297  6288 instance_detector.cc:116] could not retrieve OpenStack instance metadata: Network error: curl error: HTTP response code said error: The requested URL returned error: 404
14:22:11.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:11.164686  6287 instance_detector.cc:116] could not retrieve GCE instance metadata: Timed out: curl timeout: Timeout was reached: Resolving timed out after 1399 milliseconds
14:22:11.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.164767  6278 server_base.cc:1056] Not found: could not retrieve instance metadata: unable to detect cloud type of this node, probably running in non-cloud environment
14:22:11.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.165946  6278 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:22:11.169 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.168758  6278 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:22:11.170 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.170379  6278 hybrid_clock.cc:648] HybridClock initialized: now 1779114131170329 us; error 56 us; skew 500 ppm
14:22:11.171 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.171147  6278 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:22:11.178 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.178542  6278 webserver.cc:533] Webserver started at http://127.5.144.67:34469/ using document root <none> and password file <none>
14:22:11.179 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.179723  6278 fs_manager.cc:362] Metadata directory not provided
14:22:11.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.179970  6278 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:22:11.180 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.180526  6278 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:22:11.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.185225  6278 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data/instance:
14:22:11.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "caae1f1ea9134d65976c4c871715020a"
14:22:11.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:11 on dist-test-slave-tpwj"
14:22:11.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.186461  6278 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/wal/instance:
14:22:11.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "caae1f1ea9134d65976c4c871715020a"
14:22:11.186 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:11 on dist-test-slave-tpwj"
14:22:11.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.193945  6278 fs_manager.cc:696] Time spent creating directory manager: real 0.007s	user 0.009s	sys 0.000s
14:22:11.199 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.199535  6296 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:22:11.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.200601  6278 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.003s
14:22:11.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.201042  6278 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data,/tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/wal
14:22:11.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "caae1f1ea9134d65976c4c871715020a"
14:22:11.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:22:11 on dist-test-slave-tpwj"
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.201407  6278 fs_report.cc:389] FS layout report
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/wal
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/wal
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data/data
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:22:11.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:22:11.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.262140  6278 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:22:11.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.263830  6278 env_posix.cc:2267] Not raising this process' running threads per effective uid limit of 18446744073709551615; it is already as high as it can go
14:22:11.264 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.264369  6278 kserver.cc:163] Server-wide thread pool size limit: 3276
14:22:11.267 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.267567  6278 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:22:11.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.272367  6278 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:22:11.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.272580  6278 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:22:11.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.272789  6278 ts_tablet_manager.cc:616] Registered 0 tablets
14:22:11.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.272935  6278 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:22:11.538 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.538450  6278 rpc_server.cc:307] RPC server started. Bound to: 127.5.144.67:34483
14:22:11.539 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.538546  6408 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.5.144.67:34483 every 8 connection(s)
14:22:11.541 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.541211  6278 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/data/info.pb
14:22:11.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.551033  5697 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu as pid 6278
14:22:11.552 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.551631  5697 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskFCAImD/test-tmp/mini-kudu-cluster11540032700852923301/ts-2/wal/instance
14:22:11.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.618613  6410 heartbeater.cc:344] Connected to a master server at 127.5.144.125:42177
14:22:11.619 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.619202  6410 heartbeater.cc:461] Registering TS with master...
14:22:11.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.620785  6410 heartbeater.cc:507] Master 127.5.144.125:42177 requested a full tablet report, sending...
14:22:11.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.620868  6412 heartbeater.cc:344] Connected to a master server at 127.5.144.126:37643
14:22:11.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.621250  6412 heartbeater.cc:461] Registering TS with master...
14:22:11.622 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.622027  6412 heartbeater.cc:507] Master 127.5.144.126:37643 requested a full tablet report, sending...
14:22:11.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.624040  5817 ts_manager.cc:194] Registered new tserver with Master: caae1f1ea9134d65976c4c871715020a (127.5.144.67:34483)
14:22:11.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.624383  6409 heartbeater.cc:344] Connected to a master server at 127.5.144.124:38509
14:22:11.624 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.624776  6409 heartbeater.cc:461] Registering TS with master...
14:22:11.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.625033  5742 ts_manager.cc:194] Registered new tserver with Master: caae1f1ea9134d65976c4c871715020a (127.5.144.67:34483)
14:22:11.625 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.625635  6409 heartbeater.cc:507] Master 127.5.144.124:38509 requested a full tablet report, sending...
14:22:11.628 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.627699  5895 ts_manager.cc:194] Registered new tserver with Master: caae1f1ea9134d65976c4c871715020a (127.5.144.67:34483)
14:22:11.629 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.629202  5895 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.5.144.67:59425
14:22:11.642 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:11.641907  5697 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
14:22:11.657 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:22:11.658 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

14:22:11.668 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.5.144.126"
      port: 37643
    }
    bound_http_address {
      host: "127.5.144.126"
      port: 43153
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.5.144.125"
      port: 42177
    }
    bound_http_address {
      host: "127.5.144.125"
      port: 40717
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.5.144.124"
      port: 38509
    }
    bound_http_address {
      host: "127.5.144.124"
      port: 42681
    }
  }
}

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

14:22:11.673 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.5.144.65"
      port: 45827
    }
    bound_http_address {
      host: "127.5.144.65"
      port: 40111
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.5.144.66"
      port: 45527
    }
    bound_http_address {
      host: "127.5.144.66"
      port: 43441
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.5.144.67"
      port: 34483
    }
    bound_http_address {
      host: "127.5.144.67"
      port: 34469
    }
  }
}

14:22:11.674 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
14:22:11.877 [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)
14:22:11.935 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
14:22:12.082 [DEBUG - main] (Connection.java:748) [peer master-127.5.144.125:42177(127.5.144.125:42177)] connecting to peer
14:22:12.130 [DEBUG - main] (Connection.java:748) [peer master-127.5.144.126:37643(127.5.144.126:37643)] connecting to peer
14:22:12.135 [DEBUG - main] (Connection.java:748) [peer master-127.5.144.124:38509(127.5.144.124:38509)] connecting to peer
14:22:12.155 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.5.144.126:37643(127.5.144.126:37643)] Successfully connected to peer
14:22:12.159 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.5.144.125:42177(127.5.144.125:42177)] Successfully connected to peer
14:22:12.161 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.5.144.124:38509(127.5.144.124:38509)] Successfully connected to peer
14:22:12.277 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.5.144.124
14:22:12.277 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.5.144.126
14:22:12.279 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.5.144.125
14:22:12.493 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0xce725698, L:/127.0.0.1:33344 - R:/127.5.144.124:38509] using SASL/PLAIN
14:22:12.493 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0xaec18f9b, L:/127.0.0.1:42572 - R:/127.5.144.125:42177] using SASL/PLAIN
14:22:12.494 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0xea8d3855, L:/127.0.0.1:46990 - R:/127.5.144.126:37643] using SASL/PLAIN
14:22:12.534 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
14:22:12.535 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=491), Traces: [0ms] refreshing cache from master, [23ms] Sub RPC ConnectToMaster: sending RPC to server master-127.5.144.125:42177, [81ms] Sub RPC ConnectToMaster: sending RPC to server master-127.5.144.126:37643, [84ms] Sub RPC ConnectToMaster: sending RPC to server master-127.5.144.124:38509, [461ms] Sub RPC ConnectToMaster: received response from server master-127.5.144.125:42177: OK, [461ms] Sub RPC ConnectToMaster: received response from server master-127.5.144.126:37643: OK, [462ms] Sub RPC ConnectToMaster: received response from server master-127.5.144.124:38509: OK, deferred=null) after lookup
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.594267  5894 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:33344:
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1779114115795"
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
14:22:12.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:22:12.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:22:12.598 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:12.597429  5894 catalog_manager.cc:7059] 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-1779114115795 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
14:22:12.635 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.634855  6409 heartbeater.cc:499] Master 127.5.144.124:38509 was elected leader, sending a full tablet report...
14:22:12.692 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:894) Opened table bc737eb115a546079b8047c87eb4358e
14:22:12.714 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.713218  6205 tablet_service.cc:1511] Processing CreateTablet for tablet a5142e36a81640e78f95a3c19407eaa4 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114115795 [id=bc737eb115a546079b8047c87eb4358e]), partition=RANGE (key) PARTITION UNBOUNDED
14:22:12.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.715669  6205 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a5142e36a81640e78f95a3c19407eaa4. 1 dirs total, 0 dirs full, 0 dirs failed
14:22:12.743 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.742394  6063 tablet_service.cc:1511] Processing CreateTablet for tablet a5142e36a81640e78f95a3c19407eaa4 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114115795 [id=bc737eb115a546079b8047c87eb4358e]), partition=RANGE (key) PARTITION UNBOUNDED
14:22:12.744 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.744386  6063 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a5142e36a81640e78f95a3c19407eaa4. 1 dirs total, 0 dirs full, 0 dirs failed
14:22:12.751 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.749991  6344 tablet_service.cc:1511] Processing CreateTablet for tablet a5142e36a81640e78f95a3c19407eaa4 (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114115795 [id=bc737eb115a546079b8047c87eb4358e]), partition=RANGE (key) PARTITION UNBOUNDED
14:22:12.752 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.752645  6344 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a5142e36a81640e78f95a3c19407eaa4. 1 dirs total, 0 dirs full, 0 dirs failed
14:22:12.758 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.758061  6442 tablet_bootstrap.cc:492] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127: Bootstrap starting.
14:22:12.766 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.766052  6442 tablet_bootstrap.cc:654] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127: Neither blocks nor log segments found. Creating new log.
14:22:12.774 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.773306  6442 log.cc:826] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127: Log is configured to *not* fsync() on all Append() calls
14:22:12.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.785245  6444 tablet_bootstrap.cc:492] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805: Bootstrap starting.
14:22:12.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.786603  6442 tablet_bootstrap.cc:492] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127: No bootstrap required, opened a new log
14:22:12.787 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.787382  6442 ts_tablet_manager.cc:1403] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127: Time spent bootstrapping tablet: real 0.030s	user 0.014s	sys 0.011s
14:22:12.803 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.802611  6444 tablet_bootstrap.cc:654] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805: Neither blocks nor log segments found. Creating new log.
14:22:12.811 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.807185  6444 log.cc:826] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805: Log is configured to *not* fsync() on all Append() calls
14:22:12.820 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.819643  6445 tablet_bootstrap.cc:492] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a: Bootstrap starting.
14:22:12.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.825001  6444 tablet_bootstrap.cc:492] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805: No bootstrap required, opened a new log
14:22:12.826 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.825858  6444 ts_tablet_manager.cc:1403] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805: Time spent bootstrapping tablet: real 0.042s	user 0.011s	sys 0.019s
14:22:12.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.828563  6445 tablet_bootstrap.cc:654] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a: Neither blocks nor log segments found. Creating new log.
14:22:12.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.831063  6445 log.cc:826] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a: Log is configured to *not* fsync() on all Append() calls
14:22:12.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.847131  6442 raft_consensus.cc:359] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:12.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.848395  6442 raft_consensus.cc:385] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:22:12.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.848786  6442 raft_consensus.cc:740] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 5312fecf023a470f87a2f6f00a88f127, State: Initialized, Role: FOLLOWER
14:22:12.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.849972  6442 consensus_queue.cc:260] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [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: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:12.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.855544  6445 tablet_bootstrap.cc:492] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a: No bootstrap required, opened a new log
14:22:12.856 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.856072  6445 ts_tablet_manager.cc:1403] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a: Time spent bootstrapping tablet: real 0.038s	user 0.021s	sys 0.014s
14:22:12.857 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.856787  6442 ts_tablet_manager.cc:1434] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127: Time spent starting tablet: real 0.069s	user 0.043s	sys 0.008s
14:22:12.870 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:12.869092  6277 tablet.cc:2404] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:22:12.885 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.884773  6444 raft_consensus.cc:359] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:12.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.885596  6444 raft_consensus.cc:385] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:22:12.886 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.886036  6444 raft_consensus.cc:740] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: d189881ba5a245f998149788c77b1805, State: Initialized, Role: FOLLOWER
14:22:12.888 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.887372  6444 consensus_queue.cc:260] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [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: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:12.895 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.894528  6444 ts_tablet_manager.cc:1434] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805: Time spent starting tablet: real 0.068s	user 0.029s	sys 0.011s
14:22:12.917 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.915946  6445 raft_consensus.cc:359] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:12.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.917055  6445 raft_consensus.cc:385] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:22:12.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.917526  6445 raft_consensus.cc:740] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: caae1f1ea9134d65976c4c871715020a, State: Initialized, Role: FOLLOWER
14:22:12.919 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.918752  6445 consensus_queue.cc:260] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [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: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:12.925 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:12.924489  6445 ts_tablet_manager.cc:1434] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a: Time spent starting tablet: real 0.068s	user 0.027s	sys 0.019s
14:22:12.939 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:12.938964  6135 tablet.cc:2404] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:22:13.092 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:22:13.091579  6416 tablet.cc:2404] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:22:13.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.216632  6450 raft_consensus.cc:493] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:22:13.217 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.217134  6450 raft_consensus.cc:515] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:13.220 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.219791  6450 leader_election.cc:290] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 5312fecf023a470f87a2f6f00a88f127 (127.5.144.66:45527), d189881ba5a245f998149788c77b1805 (127.5.144.65:45827)
14:22:13.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.224337  6448 raft_consensus.cc:493] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:22:13.225 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.225082  6448 raft_consensus.cc:515] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:13.235 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.234623  6448 leader_election.cc:290] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers d189881ba5a245f998149788c77b1805 (127.5.144.65:45827), caae1f1ea9134d65976c4c871715020a (127.5.144.67:34483)
14:22:13.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.244557  6225 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "caae1f1ea9134d65976c4c871715020a" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5312fecf023a470f87a2f6f00a88f127" is_pre_election: true
14:22:13.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.245220  6083 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "caae1f1ea9134d65976c4c871715020a" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d189881ba5a245f998149788c77b1805" is_pre_election: true
14:22:13.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.245437  6225 raft_consensus.cc:2468] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate caae1f1ea9134d65976c4c871715020a in term 0.
14:22:13.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.246165  6083 raft_consensus.cc:2468] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate caae1f1ea9134d65976c4c871715020a in term 0.
14:22:13.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.246803  6298 leader_election.cc:304] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [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: 5312fecf023a470f87a2f6f00a88f127, caae1f1ea9134d65976c4c871715020a; no voters: 
14:22:13.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.247771  6450 raft_consensus.cc:2804] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Leader pre-election won for term 1
14:22:13.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.248112  6450 raft_consensus.cc:493] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:22:13.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.248472  6450 raft_consensus.cc:3060] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 0 FOLLOWER]: Advancing to term 1
14:22:13.252 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.251618  6364 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "5312fecf023a470f87a2f6f00a88f127" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "caae1f1ea9134d65976c4c871715020a" is_pre_election: true
14:22:13.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.253989  6083 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "5312fecf023a470f87a2f6f00a88f127" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d189881ba5a245f998149788c77b1805" is_pre_election: true
14:22:13.254 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.254560  6083 raft_consensus.cc:2468] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 5312fecf023a470f87a2f6f00a88f127 in term 0.
14:22:13.255 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.254851  6450 raft_consensus.cc:515] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:13.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.255796  6161 leader_election.cc:304] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [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: 5312fecf023a470f87a2f6f00a88f127, d189881ba5a245f998149788c77b1805; no voters: 
14:22:13.256 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.256138  6364 raft_consensus.cc:2393] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 1 FOLLOWER]: Leader pre-election vote request: Denying vote to candidate 5312fecf023a470f87a2f6f00a88f127 in current term 1: Already voted for candidate caae1f1ea9134d65976c4c871715020a in this term.
14:22:13.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.257736  6448 raft_consensus.cc:2804] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Leader pre-election won for term 1
14:22:13.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.258085  6448 raft_consensus.cc:493] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:22:13.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.258406  6448 raft_consensus.cc:3060] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 0 FOLLOWER]: Advancing to term 1
14:22:13.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.258731  6225 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "caae1f1ea9134d65976c4c871715020a" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "5312fecf023a470f87a2f6f00a88f127"
14:22:13.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.259794  6450 leader_election.cc:290] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [CANDIDATE]: Term 1 election: Requested vote from peers 5312fecf023a470f87a2f6f00a88f127 (127.5.144.66:45527), d189881ba5a245f998149788c77b1805 (127.5.144.65:45827)
14:22:13.262 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.261746  6083 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "caae1f1ea9134d65976c4c871715020a" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d189881ba5a245f998149788c77b1805"
14:22:13.265 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.262511  6083 raft_consensus.cc:3060] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 0 FOLLOWER]: Advancing to term 1
14:22:13.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.267041  6448 raft_consensus.cc:515] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:13.268 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.268404  6225 raft_consensus.cc:2393] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 1 FOLLOWER]: Leader election vote request: Denying vote to candidate caae1f1ea9134d65976c4c871715020a in current term 1: Already voted for candidate 5312fecf023a470f87a2f6f00a88f127 in this term.
14:22:13.270 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.270130  6083 raft_consensus.cc:2468] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate caae1f1ea9134d65976c4c871715020a in term 1.
14:22:13.271 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.271407  6300 leader_election.cc:304] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [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: caae1f1ea9134d65976c4c871715020a, d189881ba5a245f998149788c77b1805; no voters: 
14:22:13.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.271714  6082 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "5312fecf023a470f87a2f6f00a88f127" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "d189881ba5a245f998149788c77b1805"
14:22:13.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.272447  6450 raft_consensus.cc:2804] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 1 FOLLOWER]: Leader election won for term 1
14:22:13.272 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.272588  6082 raft_consensus.cc:2393] T a5142e36a81640e78f95a3c19407eaa4 P d189881ba5a245f998149788c77b1805 [term 1 FOLLOWER]: Leader election vote request: Denying vote to candidate 5312fecf023a470f87a2f6f00a88f127 in current term 1: Already voted for candidate caae1f1ea9134d65976c4c871715020a in this term.
14:22:13.273 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.272509  6448 leader_election.cc:290] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [CANDIDATE]: Term 1 election: Requested vote from peers d189881ba5a245f998149788c77b1805 (127.5.144.65:45827), caae1f1ea9134d65976c4c871715020a (127.5.144.67:34483)
14:22:13.274 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.273624  6364 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a5142e36a81640e78f95a3c19407eaa4" candidate_uuid: "5312fecf023a470f87a2f6f00a88f127" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "caae1f1ea9134d65976c4c871715020a"
14:22:13.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.275215  6450 raft_consensus.cc:697] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [term 1 LEADER]: Becoming Leader. State: Replica: caae1f1ea9134d65976c4c871715020a, State: Running, Role: LEADER
14:22:13.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.276556  6450 consensus_queue.cc:237] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [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: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } }
14:22:13.285 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.284197  6160 leader_election.cc:304] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [CANDIDATE]: Term 1 election: Election decided. Result: candidate lost. Election summary: received 3 responses out of 3 voters: 1 yes votes; 2 no votes. yes voters: 5312fecf023a470f87a2f6f00a88f127; no voters: caae1f1ea9134d65976c4c871715020a, d189881ba5a245f998149788c77b1805
14:22:13.286 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.286132  6448 raft_consensus.cc:2749] T a5142e36a81640e78f95a3c19407eaa4 P 5312fecf023a470f87a2f6f00a88f127 [term 1 FOLLOWER]: Leader election lost for term 1. Reason: could not achieve majority
14:22:13.288 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.286391  5894 catalog_manager.cc:5697] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a reported cstate change: term changed from 0 to 1, leader changed from <none> to caae1f1ea9134d65976c4c871715020a (127.5.144.67). New cstate: current_term: 1 leader_uuid: "caae1f1ea9134d65976c4c871715020a" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "caae1f1ea9134d65976c4c871715020a" member_type: VOTER last_known_addr { host: "127.5.144.67" port: 34483 } health_report { overall_health: HEALTHY } } }
14:22:13.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.672701  6450 consensus_queue.cc:1048] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [LEADER]: Connected to new peer: Peer: permanent_uuid: "d189881ba5a245f998149788c77b1805" member_type: VOTER last_known_addr { host: "127.5.144.65" port: 45827 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:22:13.697 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:13.696885  6450 consensus_queue.cc:1048] T a5142e36a81640e78f95a3c19407eaa4 P caae1f1ea9134d65976c4c871715020a [LEADER]: Connected to new peer: Peer: permanent_uuid: "5312fecf023a470f87a2f6f00a88f127" member_type: VOTER last_known_addr { host: "127.5.144.66" port: 45527 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:22:13.747 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2538) Learned about tablet a5142e36a81640e78f95a3c19407eaa4 for table 'org.apache.kudu.client.TestHybridTime-1779114115795' with partition [<start>, <end>)
14:22:13.753 [DEBUG - kudu-nio-2] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299994, tablet=a5142e36a81640e78f95a3c19407eaa4@[5312fecf023a470f87a2f6f00a88f127(127.5.144.66:45527),caae1f1ea9134d65976c4c871715020a(127.5.144.67:34483)[L],d189881ba5a245f998149788c77b1805(127.5.144.65:45827)]}]
14:22:13.754 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=20), Traces: [0ms] refreshing cache from master, [1ms] Sub RPC GetTableLocations: sending RPC to server master-127.5.144.124:38509, [11ms] Sub RPC GetTableLocations: received response from server master-127.5.144.124:38509: OK, deferred=null) after lookup
14:22:13.773 [DEBUG - kudu-nio-2] (Connection.java:748) [peer caae1f1ea9134d65976c4c871715020a(127.5.144.67:34483)] connecting to peer
14:22:13.775 [DEBUG - kudu-nio-3] (Connection.java:757) [peer caae1f1ea9134d65976c4c871715020a(127.5.144.67:34483)] Successfully connected to peer
14:22:13.836 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x335b11c0, L:/127.0.0.1:40872 - R:/127.5.144.67:34483] using TOKEN/null
14:22:13.963 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:22:13.985 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:22:14.005 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:22:14.010 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1143567581(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:22:14.037 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:22:14.037 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1468444270(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:22:14.059 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:22:14.060 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@541977669(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:22:14.083 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:22:14.136 [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-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.149 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7287251512706019329) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.160 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7287251512706019333) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.171 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7287251512706019337) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.185 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7287251512706019341) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.197 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7287251512706019345) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.208 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7287251512706019349) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.217 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7287251512706019350) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114115795, tablet=a5142e36a81640e78f95a3c19407eaa4, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:22:14.221 [DEBUG - main] (Connection.java:575) [peer caae1f1ea9134d65976c4c871715020a(127.5.144.67:34483)] disconnecting while in state READY
14:22:14.222 [DEBUG - main] (Connection.java:575) [peer master-127.5.144.125:42177(127.5.144.125:42177)] disconnecting while in state READY
14:22:14.222 [DEBUG - main] (Connection.java:575) [peer master-127.5.144.126:37643(127.5.144.126:37643)] disconnecting while in state READY
14:22:14.226 [DEBUG - main] (Connection.java:575) [peer master-127.5.144.124:38509(127.5.144.124:38509)] disconnecting while in state READY
14:22:14.227 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.5.144.125:42177(127.5.144.125:42177)] handling channelInactive
14:22:14.228 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.5.144.125:42177(127.5.144.125:42177)] cleaning up while in state READY due to: connection closed
14:22:14.228 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.5.144.126:37643(127.5.144.126:37643)] handling channelInactive
14:22:14.228 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.5.144.124:38509(127.5.144.124:38509)] handling channelInactive
14:22:14.229 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.5.144.126:37643(127.5.144.126:37643)] cleaning up while in state READY due to: connection closed
14:22:14.229 [DEBUG - kudu-nio-3] (Connection.java:239) [peer caae1f1ea9134d65976c4c871715020a(127.5.144.67:34483)] handling channelInactive
14:22:14.229 [DEBUG - kudu-nio-3] (Connection.java:709) [peer caae1f1ea9134d65976c4c871715020a(127.5.144.67:34483)] cleaning up while in state READY due to: connection closed
14:22:14.229 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.5.144.124:38509(127.5.144.124:38509)] cleaning up while in state READY due to: connection closed
14:22:14.230 [DEBUG - main] (AsyncKuduClient.java:2704) Releasing all remaining resources
14:22:14.236 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:14.235442  5697 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu with pid 5977
14:22:14.275 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:14.274958  5697 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu with pid 6136
14:22:14.325 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:14.325268  5697 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu with pid 6278
14:22:14.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:14.358807  5697 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu with pid 5709
14:22:14.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:14.390491  5697 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu with pid 5779
14:22:14.418 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:22:14.417588  5697 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskFCAImD/build/tsan/bin/kudu with pid 5854
14:22:15.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:22:15Z chronyd exiting

Time: 20.75

OK (1 test)

