JUnit version 4.13.2
.14:23:04.138 [DEBUG - main] (RandomUtils.java:49) Using random seed: 1779114184129
14:23:04.165 [INFO - main] (RetryRule.java:138) Creating RetryStatement with result reporter and retry count of 0 (no retries)
14:23:04.200 [INFO - main] (KuduTestHarness.java:155) Creating a new MiniKuduCluster...
14:23:04.208 [INFO - main] (TempDirUtils.java:71) Using the temp directory defined by TEST_TMPDIR: /tmp/dist-test-taskhqtKoN/test-tmp
14:23:04.236 [INFO - main] (KuduBinaryLocator.java:83) Using Kudu binary directory specified by system property 'kuduBinDir': ../../../build/asan/bin
14:23:04.238 [INFO - main] (MiniKuduCluster.java:217) Starting process: [/tmp/dist-test-taskhqtKoN/java/build/dist-test/../../../build/asan/bin/kudu, test, mini_cluster, --serialization=pb]
14:23:04.432 [DEBUG - main] (MiniKuduCluster.java:185) Request: create_cluster {
  num_masters: 3
  num_tservers: 3
  enable_kerberos: false
  cluster_root: "/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672"
  hms_mode: NONE
  mini_kdc_options {
  }
  principal: "kudu"
  mini_oidc_options {
  }
}

14:23:04.640 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:23:04.641 [DEBUG - main] (MiniKuduCluster.java:185) Request: start_cluster {
}

14:23:04.645 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:04.644598  6321 mini_oidc.cc:150] Starting JWKS server
14:23:04.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:04.647445  6321 webserver.cc:533] Webserver started at http://127.0.0.1:45563/ using document root <none> and password file <none>
14:23:04.648 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:04.647737  6321 mini_oidc.cc:186] Starting OIDC Discovery server
14:23:04.649 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:04.649420  6321 webserver.cc:533] Webserver started at http://127.0.0.1:37513/ using document root <none> and password file <none>
14:23:04.669 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:23:04Z chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -NTS -SECHASH -IPV6 +DEBUG)
14:23:04.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:23:04Z Disabled control of system clock
14:23:04.683 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:04.683015  6321 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:04.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:04.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/wal
14:23:04.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data
14:23:04.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:23:04.685 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:23:04.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:23:04.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:04.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:04.686 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:04.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/logs
14:23:04.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data/info.pb
14:23:04.687 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:04.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:23:04.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:23:04.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:23:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:23:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:04.689 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:23:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:23:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:23:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:23:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.126:40335
14:23:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.126
14:23:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:04.690 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:23:04.691 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:23:04.692 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45563/jwks.json with env {}
14:23:05.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.035531  6332 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:23:05.036 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.035912  6332 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:23:05.037 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.035993  6332 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:23:05.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.045153  6332 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:23:05.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.045279  6332 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:23:05.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.045346  6332 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:23:05.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.045387  6332 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:23:05.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.045442  6332 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:23:05.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.045475  6332 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:45563/jwks.json
14:23:05.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.056497  6332 master_runner.cc:387] Master server non-default flags:
14:23:05.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:05.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:05.057 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:05.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:05.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data
14:23:05.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/wal
14:23:05.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:23:05.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:05.058 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.126:40335
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45563/jwks.json
14:23:05.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:05.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:05.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data/info.pb
14:23:05.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.126
14:23:05.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:05.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:23:05.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/logs
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:23:05.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:23:05.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:23:05.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:23:05.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:53 UTC on e7f111948823
14:23:05.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15853
14:23:05.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
14:23:05.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.058799  6332 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:23:05.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.061547  6332 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:23:05.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.071815  6337 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:23:05.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.071794  6338 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:23:05.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.071941  6340 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:23:05.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.072750  6332 server_base.cc:1061] running on GCE node
14:23:05.074 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.074477  6332 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:23:05.076 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.076296  6332 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:23:05.077 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.077570  6332 hybrid_clock.cc:648] HybridClock initialized: now 1779114185077497 us; error 66 us; skew 500 ppm
14:23:05.078 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.078101  6332 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:23:05.081 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.080919  6332 webserver.cc:533] Webserver started at http://127.6.44.126:43525/ using document root <none> and password file <none>
14:23:05.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.081918  6332 fs_manager.cc:362] Metadata directory not provided
14:23:05.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.082078  6332 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:23:05.082 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.082581  6332 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:23:05.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.085386  6332 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data/instance:
14:23:05.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "82f068dceb854634bfcbcbbeae37715c"
14:23:05.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:05 on dist-test-slave-psp9"
14:23:05.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.086496  6332 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/wal/instance:
14:23:05.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "82f068dceb854634bfcbcbbeae37715c"
14:23:05.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:05 on dist-test-slave-psp9"
14:23:05.093 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.093039  6332 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.005s	sys 0.000s
14:23:05.097 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.097321  6346 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:23:05.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.099066  6332 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.000s
14:23:05.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.099246  6332 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data,/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/wal
14:23:05.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "82f068dceb854634bfcbcbbeae37715c"
14:23:05.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:05 on dist-test-slave-psp9"
14:23:05.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.099426  6332 fs_report.cc:389] FS layout report
14:23:05.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:23:05.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/wal
14:23:05.100 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/wal
14:23:05.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data/data
14:23:05.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:23:05.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:23:05.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:23:05.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:23:05.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:23:05.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:23:05.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:23:05.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:23:05.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:23:05.102 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:23:05.103 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:23:05.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.127628  6332 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:23:05.128 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.128616  6332 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:23:05.129 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.128872  6332 kserver.cc:163] Server-wide thread pool size limit: 3276
14:23:05.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.150908  6332 rpc_server.cc:307] RPC server started. Bound to: 127.6.44.126:40335
14:23:05.151 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.150933  6397 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.44.126:40335 every 8 connection(s)
14:23:05.152 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.152618  6332 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/data/info.pb
14:23:05.155 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.155486  6321 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu as pid 6332
14:23:05.156 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.155838  6321 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-0/wal/instance
14:23:05.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.157763  6398 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:23:05.158 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.158583  6321 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:05.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:05.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/wal
14:23:05.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data
14:23:05.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:23:05.159 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:23:05.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:23:05.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:05.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:05.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:05.160 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/logs
14:23:05.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data/info.pb
14:23:05.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:05.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:23:05.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:23:05.161 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:23:05.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:23:05.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:05.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:05.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:05.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:23:05.162 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:23:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:23:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:23:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.125:39087
14:23:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.125
14:23:05.163 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:23:05.164 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:23:05.165 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45563/jwks.json with env {}
14:23:05.166 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.165609  6398 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } has no permanent_uuid. Determining permanent_uuid...
14:23:05.193 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.192399  6398 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } has no permanent_uuid. Determining permanent_uuid...
14:23:05.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.193821  6348 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.6.44.125:39087: connect: Connection refused (error 111)
14:23:05.196 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.196014  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.125:39087: Network error: Client connection negotiation failed: client connection to 127.6.44.125:39087: connect: Connection refused (error 111)
14:23:05.246 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.245446  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } attempt: 1
14:23:05.248 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.248193  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.125:39087: Network error: Client connection negotiation failed: client connection to 127.6.44.125:39087: connect: Connection refused (error 111)
14:23:05.317 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.316578  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } attempt: 2
14:23:05.319 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.319072  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.125:39087: Network error: Client connection negotiation failed: client connection to 127.6.44.125:39087: connect: Connection refused (error 111)
14:23:05.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.410384  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } attempt: 3
14:23:05.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.413070  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.125:39087: Network error: Client connection negotiation failed: client connection to 127.6.44.125:39087: connect: Connection refused (error 111)
14:23:05.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.535225  6400 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:23:05.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.535600  6400 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:23:05.536 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.535697  6400 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:23:05.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.544298  6400 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:23:05.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.544450  6400 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:23:05.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.544512  6400 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:23:05.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.544559  6400 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:23:05.545 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.544620  6400 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:23:05.546 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.544701  6400 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:45563/jwks.json
14:23:05.557 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.556454  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } attempt: 4
14:23:05.559 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.559156  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.125:39087: Network error: Client connection negotiation failed: client connection to 127.6.44.125:39087: connect: Connection refused (error 111)
14:23:05.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.561461  6400 master_runner.cc:387] Master server non-default flags:
14:23:05.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:05.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:05.562 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:05.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:05.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data
14:23:05.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/wal
14:23:05.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:23:05.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:05.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:05.563 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:05.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:23:05.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.125:39087
14:23:05.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:23:05.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:23:05.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:23:05.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45563/jwks.json
14:23:05.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:05.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:05.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data/info.pb
14:23:05.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.125
14:23:05.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:05.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:23:05.565 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:05.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:23:05.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:23:05.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:05.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/logs
14:23:05.566 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:05.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:23:05.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:23:05.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:23:05.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:23:05.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:23:05.567 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:23:05.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:53 UTC on e7f111948823
14:23:05.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15853
14:23:05.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
14:23:05.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.563903  6400 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:23:05.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.566646  6400 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:23:05.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.577792  6409 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:23:05.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.578648  6408 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:23:05.580 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.579816  6411 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:23:05.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.582165  6400 server_base.cc:1061] running on GCE node
14:23:05.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.583359  6400 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:23:05.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.585408  6400 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:23:05.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.586747  6400 hybrid_clock.cc:648] HybridClock initialized: now 1779114185586683 us; error 88 us; skew 500 ppm
14:23:05.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.587121  6400 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:23:05.589 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.589600  6400 webserver.cc:533] Webserver started at http://127.6.44.125:32933/ using document root <none> and password file <none>
14:23:05.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.590416  6400 fs_manager.cc:362] Metadata directory not provided
14:23:05.590 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.590515  6400 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:23:05.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.590986  6400 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:23:05.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.593633  6400 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data/instance:
14:23:05.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "335e03033f444e7e99254e41d4256e19"
14:23:05.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:05 on dist-test-slave-psp9"
14:23:05.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.594532  6400 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/wal/instance:
14:23:05.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "335e03033f444e7e99254e41d4256e19"
14:23:05.594 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:05 on dist-test-slave-psp9"
14:23:05.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.600440  6400 fs_manager.cc:696] Time spent creating directory manager: real 0.005s	user 0.004s	sys 0.000s
14:23:05.605 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.604632  6417 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:23:05.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.606140  6400 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.001s	sys 0.000s
14:23:05.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.606344  6400 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data,/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/wal
14:23:05.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "335e03033f444e7e99254e41d4256e19"
14:23:05.606 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:05 on dist-test-slave-psp9"
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.606546  6400 fs_report.cc:389] FS layout report
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/wal
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/wal
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data/data
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:23:05.607 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:23:05.608 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:23:05.630 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.630002  6400 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:23:05.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.631151  6400 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:23:05.631 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.631409  6400 kserver.cc:163] Server-wide thread pool size limit: 3276
14:23:05.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.654060  6400 rpc_server.cc:307] RPC server started. Bound to: 127.6.44.125:39087
14:23:05.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.654081  6468 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.44.125:39087 every 8 connection(s)
14:23:05.656 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.656036  6400 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/data/info.pb
14:23:05.661 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.660916  6469 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:23:05.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.663115  6321 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu as pid 6400
14:23:05.663 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.663268  6321 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-1/wal/instance
14:23:05.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.664202  6321 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:05.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:05.664 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/wal
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:05.665 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/logs
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data/info.pb
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:05.666 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) master
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.124:35199
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.124
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:05.667 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:05.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:05.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:05.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:05.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:23:05.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:05.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:23:05.668 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45563/jwks.json with env {}
14:23:05.670 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.669075  6469 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } has no permanent_uuid. Determining permanent_uuid...
14:23:05.684 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.684335  6469 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } has no permanent_uuid. Determining permanent_uuid...
14:23:05.695 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.695195  6469 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } has no permanent_uuid. Determining permanent_uuid...
14:23:05.696 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.696516  6421 proxy.cc:239] Call had error, refreshing address and retrying: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:05.698 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.698410  6469 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:05.748 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.747901  6469 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 1
14:23:05.750 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.750399  6469 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:05.819 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.818722  6469 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 2
14:23:05.821 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.821426  6469 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:05.859 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.858542  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } attempt: 5
14:23:05.866 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.866330  6398 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } has no permanent_uuid. Determining permanent_uuid...
14:23:05.868 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.868321  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:05.913 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.912782  6469 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 3
14:23:05.915 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.915258  6469 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:05.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.919579  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 1
14:23:05.922 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.921748  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:05.990 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:05.990082  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 2
14:23:05.992 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:05.992561  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:06.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.024380  6471 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:23:06.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.024773  6471 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:23:06.025 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.024899  6471 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:23:06.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.033538  6471 flags.cc:432] Enabled experimental flag: --ipki_ca_key_size=768
14:23:06.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.033691  6471 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:23:06.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.033784  6471 flags.cc:432] Enabled experimental flag: --tsk_num_rsa_bits=512
14:23:06.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.033849  6471 flags.cc:432] Enabled experimental flag: --rpc_reuseport=true
14:23:06.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.033926  6471 flags.cc:432] Enabled experimental flag: --enable_jwt_token_auth=true
14:23:06.034 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.033977  6471 flags.cc:432] Enabled experimental flag: --jwks_url=http://localhost:45563/jwks.json
14:23:06.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.044827  6471 master_runner.cc:387] Master server non-default flags:
14:23:06.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:06.045 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:06.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:06.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:06.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data
14:23:06.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/wal
14:23:06.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_ca_key_size=768
14:23:06.046 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --master_addresses=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tsk_num_rsa_bits=512
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.124:35199
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_reuseport=true
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_jwt_token_auth=true
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --jwks_url=http://localhost:45563/jwks.json
14:23:06.047 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data/info.pb
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.124
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:23:06.048 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:23:06.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:06.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/logs
14:23:06.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:06.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:23:06.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:23:06.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Master server version:
14:23:06.049 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:23:06.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:23:06.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:23:06.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:53 UTC on e7f111948823
14:23:06.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15853
14:23:06.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
14:23:06.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.047358  6471 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:23:06.050 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.050148  6471 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:23:06.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.058557  6469 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 4
14:23:06.059 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.058923  6480 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:23:06.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.058898  6479 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:23:06.060 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.059724  6482 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:23:06.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.060976  6471 server_base.cc:1061] running on GCE node
14:23:06.061 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.061509  6469 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:06.062 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.061952  6471 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:23:06.063 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.063614  6471 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:23:06.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.064867  6471 hybrid_clock.cc:648] HybridClock initialized: now 1779114186064795 us; error 58 us; skew 500 ppm
14:23:06.065 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.065346  6471 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:23:06.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.067864  6471 webserver.cc:533] Webserver started at http://127.6.44.124:37225/ using document root <none> and password file <none>
14:23:06.068 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.068733  6471 fs_manager.cc:362] Metadata directory not provided
14:23:06.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.068873  6471 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:23:06.069 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.069351  6471 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:23:06.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.071924  6471 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data/instance:
14:23:06.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3b97b8274f5a40b3abafc17918036a27"
14:23:06.072 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:06 on dist-test-slave-psp9"
14:23:06.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.072953  6471 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/wal/instance:
14:23:06.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3b97b8274f5a40b3abafc17918036a27"
14:23:06.073 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:06 on dist-test-slave-psp9"
14:23:06.079 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.079135  6471 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.007s	sys 0.000s
14:23:06.083 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.083091  6488 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:23:06.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.085115  6471 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.001s
14:23:06.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.085367  6471 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data,/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/wal
14:23:06.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "3b97b8274f5a40b3abafc17918036a27"
14:23:06.085 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:06 on dist-test-slave-psp9"
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.085574  6471 fs_report.cc:389] FS layout report
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/wal
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/wal
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data/data
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:23:06.086 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:23:06.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:23:06.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:23:06.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:23:06.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:23:06.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:23:06.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:23:06.087 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:23:06.099 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.098836  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 3
14:23:06.101 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.100951  6398 consensus_peers.cc:760] Error getting permanent uuid from config peer 127.6.44.124:35199: Network error: Client connection negotiation failed: client connection to 127.6.44.124:35199: connect: Connection refused (error 111)
14:23:06.108 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.108459  6471 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:23:06.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.109402  6471 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:23:06.109 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.109642  6471 kserver.cc:163] Server-wide thread pool size limit: 3276
14:23:06.131 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.131314  6471 rpc_server.cc:307] RPC server started. Bound to: 127.6.44.124:35199
14:23:06.132 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.131321  6539 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.44.124:35199 every 8 connection(s)
14:23:06.133 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.133291  6471 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/data/info.pb
14:23:06.136 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.136484  6321 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu as pid 6471
14:23:06.137 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.136744  6321 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/master-2/wal/instance
14:23:06.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.139528  6540 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:23:06.148 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.147459  6540 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } has no permanent_uuid. Determining permanent_uuid...
14:23:06.168 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.167546  6540 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } has no permanent_uuid. Determining permanent_uuid...
14:23:06.175 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.174572  6540 sys_catalog.cc:422] member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } has no permanent_uuid. Determining permanent_uuid...
14:23:06.185 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.185202  6540 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: Bootstrap starting.
14:23:06.189 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.189029  6540 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: Neither blocks nor log segments found. Creating new log.
14:23:06.190 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.190228  6540 log.cc:826] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: Log is configured to *not* fsync() on all Append() calls
14:23:06.194 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.193681  6540 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: No bootstrap required, opened a new log
14:23:06.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.199623  6540 raft_consensus.cc:359] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.200012  6540 raft_consensus.cc:385] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:23:06.200 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.200137  6540 raft_consensus.cc:740] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 3b97b8274f5a40b3abafc17918036a27, State: Initialized, Role: FOLLOWER
14:23:06.201 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.200932  6540 consensus_queue.cc:260] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [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: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.201957  6547 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [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: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.202 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.202199  6547 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [sys.catalog]: This master's current role is: FOLLOWER
14:23:06.203 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.203301  6540 sys_catalog.cc:565] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [sys.catalog]: configured and running, proceeding with master startup.
14:23:06.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.215003  6558 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:23:06.215 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.215142  6558 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:23:06.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.211201  6471 master_runner.cc:187] Error getting master registration for 127.6.44.126:40335: OK, instance_id { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" instance_seqno: 1779114185144798 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:23:06.216 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.216681  6471 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:23:06.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.278206  6398 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 4
14:23:06.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.291060  6398 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c: Bootstrap starting.
14:23:06.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.295524  6398 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c: Neither blocks nor log segments found. Creating new log.
14:23:06.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.297012  6398 log.cc:826] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c: Log is configured to *not* fsync() on all Append() calls
14:23:06.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.301007  6398 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c: No bootstrap required, opened a new log
14:23:06.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.307070  6398 raft_consensus.cc:359] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.307435  6398 raft_consensus.cc:385] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:23:06.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.307567  6398 raft_consensus.cc:740] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 82f068dceb854634bfcbcbbeae37715c, State: Initialized, Role: FOLLOWER
14:23:06.308 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.308368  6398 consensus_queue.cc:260] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [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: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.309 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.309525  6561 sys_catalog.cc:455] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [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: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.310 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.309870  6561 sys_catalog.cc:458] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: This master's current role is: FOLLOWER
14:23:06.311 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.310958  6398 sys_catalog.cc:565] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: configured and running, proceeding with master startup.
14:23:06.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.323779  6572 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:23:06.324 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.324508  6572 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:23:06.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.322372  6332 master_runner.cc:187] Error getting master registration for 127.6.44.125:39087: OK, instance_id { permanent_uuid: "335e03033f444e7e99254e41d4256e19" instance_seqno: 1779114185648139 } error { code: CATALOG_MANAGER_NOT_INITIALIZED status { code: SERVICE_UNAVAILABLE message: "Catalog manager is not initialized. State: Starting" } }
14:23:06.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.328274  6332 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:23:06.361 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.360816  6469 consensus_peers.cc:770] Retrying to get permanent uuid for remote peer: member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } attempt: 5
14:23:06.362 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.362087  6494 tablet.cc:2404] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:23:06.373 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.373332  6469 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: Bootstrap starting.
14:23:06.378 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.377739  6469 tablet_bootstrap.cc:654] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: Neither blocks nor log segments found. Creating new log.
14:23:06.380 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.379864  6469 log.cc:826] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: Log is configured to *not* fsync() on all Append() calls
14:23:06.382 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.382365  6352 tablet.cc:2404] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:23:06.383 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.383268  6469 tablet_bootstrap.cc:492] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: No bootstrap required, opened a new log
14:23:06.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.389312  6469 raft_consensus.cc:359] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.389719  6469 raft_consensus.cc:385] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:23:06.390 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.389847  6469 raft_consensus.cc:740] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 335e03033f444e7e99254e41d4256e19, State: Initialized, Role: FOLLOWER
14:23:06.392 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.391772  6469 consensus_queue.cc:260] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [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: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.412034  6575 sys_catalog.cc:455] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [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: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.413168  6575 sys_catalog.cc:458] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [sys.catalog]: This master's current role is: FOLLOWER
14:23:06.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.413897  6469 sys_catalog.cc:565] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [sys.catalog]: configured and running, proceeding with master startup.
14:23:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.435858  6400 master_runner.cc:264] No leader master found from master 335e03033f444e7e99254e41d4256e19
14:23:06.436 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.436007  6400 master_runner.cc:419] Couldn't verify the masters in the cluster. Trying again...
14:23:06.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.440402  6586 catalog_manager.cc:1594] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: loading cluster ID for follower catalog manager: Not found: cluster ID entry not found
14:23:06.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:06.441056  6586 catalog_manager.cc:883] Not found: cluster ID entry not found: failed to prepare follower catalog manager, will retry
14:23:06.470 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.466714  6561 raft_consensus.cc:493] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:23:06.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.467082  6561 raft_consensus.cc:515] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.469055  6561 leader_election.cc:290] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 335e03033f444e7e99254e41d4256e19 (127.6.44.125:39087), 3b97b8274f5a40b3abafc17918036a27 (127.6.44.124:35199)
14:23:06.471 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.470323  6515 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "82f068dceb854634bfcbcbbeae37715c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3b97b8274f5a40b3abafc17918036a27" is_pre_election: true
14:23:06.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.471904  6515 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 82f068dceb854634bfcbcbbeae37715c in term 0.
14:23:06.479 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.473150  6350 leader_election.cc:304] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [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: 3b97b8274f5a40b3abafc17918036a27, 82f068dceb854634bfcbcbbeae37715c; no voters: 
14:23:06.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.474516  6444 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "82f068dceb854634bfcbcbbeae37715c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "335e03033f444e7e99254e41d4256e19" is_pre_election: true
14:23:06.480 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.475167  6444 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 82f068dceb854634bfcbcbbeae37715c in term 0.
14:23:06.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.481921  6561 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Leader pre-election won for term 1
14:23:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.482162  6561 raft_consensus.cc:493] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:23:06.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.482262  6561 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 0 FOLLOWER]: Advancing to term 1
14:23:06.490 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.490092  6561 raft_consensus.cc:515] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.497944  6444 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "82f068dceb854634bfcbcbbeae37715c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "335e03033f444e7e99254e41d4256e19"
14:23:06.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.498232  6444 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [term 0 FOLLOWER]: Advancing to term 1
14:23:06.507 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.502168  6444 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 82f068dceb854634bfcbcbbeae37715c in term 1.
14:23:06.508 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.502916  6348 leader_election.cc:304] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [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: 335e03033f444e7e99254e41d4256e19, 82f068dceb854634bfcbcbbeae37715c; no voters: 
14:23:06.516 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.511354  6515 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "00000000000000000000000000000000" candidate_uuid: "82f068dceb854634bfcbcbbeae37715c" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "3b97b8274f5a40b3abafc17918036a27"
14:23:06.517 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.511637  6515 raft_consensus.cc:3060] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [term 0 FOLLOWER]: Advancing to term 1
14:23:06.520 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.515815  6515 raft_consensus.cc:2468] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 82f068dceb854634bfcbcbbeae37715c in term 1.
14:23:06.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.517686  6561 leader_election.cc:290] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [CANDIDATE]: Term 1 election: Requested vote from peers 335e03033f444e7e99254e41d4256e19 (127.6.44.125:39087), 3b97b8274f5a40b3abafc17918036a27 (127.6.44.124:35199)
14:23:06.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.518034  6561 raft_consensus.cc:2804] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 1 FOLLOWER]: Leader election won for term 1
14:23:06.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.518219  6561 raft_consensus.cc:697] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [term 1 LEADER]: Becoming Leader. State: Replica: 82f068dceb854634bfcbcbbeae37715c, State: Running, Role: LEADER
14:23:06.521 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.518874  6561 consensus_queue.cc:237] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [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: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } }
14:23:06.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.534942  6588 sys_catalog.cc:455] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: SysCatalogTable state changed. Reason: New leader 82f068dceb854634bfcbcbbeae37715c. Latest consensus state: current_term: 1 leader_uuid: "82f068dceb854634bfcbcbbeae37715c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.537 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.536701  6588 sys_catalog.cc:458] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: This master's current role is: LEADER
14:23:06.544 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.544339  6590 catalog_manager.cc:1511] Loading table and tablet metadata into memory...
14:23:06.548 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.547715  6590 catalog_manager.cc:1520] Initializing Kudu cluster ID...
14:23:06.569 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.568735  6515 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [term 1 FOLLOWER]: Refusing update from remote peer 82f068dceb854634bfcbcbbeae37715c: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:23:06.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.570518  6588 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [LEADER]: Connected to new peer: Peer: permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:23:06.579 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.578516  6444 raft_consensus.cc:1275] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [term 1 FOLLOWER]: Refusing update from remote peer 82f068dceb854634bfcbcbbeae37715c: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:23:06.581 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.580152  6588 consensus_queue.cc:1048] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [LEADER]: Connected to new peer: Peer: permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:23:06.617 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.617163  6591 mvcc.cc:204] Tried to move back new op lower bound from 7287251708172513280 to 7287251708011556864. Current Snapshot: MvccSnapshot[applied={T|T < 7287251708172513280}]
14:23:06.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.620330  6575 sys_catalog.cc:455] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 82f068dceb854634bfcbcbbeae37715c. Latest consensus state: current_term: 1 leader_uuid: "82f068dceb854634bfcbcbbeae37715c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.621 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.620640  6575 sys_catalog.cc:458] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [sys.catalog]: This master's current role is: FOLLOWER
14:23:06.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.643365  6575 sys_catalog.cc:455] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "82f068dceb854634bfcbcbbeae37715c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.644 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.643627  6575 sys_catalog.cc:458] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19 [sys.catalog]: This master's current role is: FOLLOWER
14:23:06.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.649176  6547 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [sys.catalog]: SysCatalogTable state changed. Reason: New leader 82f068dceb854634bfcbcbbeae37715c. Latest consensus state: current_term: 1 leader_uuid: "82f068dceb854634bfcbcbbeae37715c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.650 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.649470  6547 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [sys.catalog]: This master's current role is: FOLLOWER
14:23:06.654 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.653895  6592 mvcc.cc:204] Tried to move back new op lower bound from 7287251708172513280 to 7287251708011556864. Current Snapshot: MvccSnapshot[applied={T|T < 7287251708172513280}]
14:23:06.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.671236  6561 sys_catalog.cc:455] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "82f068dceb854634bfcbcbbeae37715c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.672 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.671591  6561 sys_catalog.cc:458] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: This master's current role is: LEADER
14:23:06.673 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.672600  6547 sys_catalog.cc:455] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [sys.catalog]: SysCatalogTable state changed. Reason: Replicated consensus-only round. Latest consensus state: current_term: 1 leader_uuid: "82f068dceb854634bfcbcbbeae37715c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.672616  6561 sys_catalog.cc:455] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: SysCatalogTable state changed. Reason: Peer health change. Latest consensus state: current_term: 1 leader_uuid: "82f068dceb854634bfcbcbbeae37715c" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "82f068dceb854634bfcbcbbeae37715c" member_type: VOTER last_known_addr { host: "127.6.44.126" port: 40335 } } peers { permanent_uuid: "335e03033f444e7e99254e41d4256e19" member_type: VOTER last_known_addr { host: "127.6.44.125" port: 39087 } } peers { permanent_uuid: "3b97b8274f5a40b3abafc17918036a27" member_type: VOTER last_known_addr { host: "127.6.44.124" port: 35199 } } }
14:23:06.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.672837  6547 sys_catalog.cc:458] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27 [sys.catalog]: This master's current role is: FOLLOWER
14:23:06.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.672883  6561 sys_catalog.cc:458] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c [sys.catalog]: This master's current role is: LEADER
14:23:06.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.676090  6590 catalog_manager.cc:1383] Generated new cluster ID: d9d07eeaac9644c7ad32aa86fe79230e
14:23:06.680 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.676226  6590 catalog_manager.cc:1531] Initializing Kudu internal certificate authority...
14:23:06.717 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.717306  6590 catalog_manager.cc:1406] Generated new certificate authority record
14:23:06.720 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.719787  6590 catalog_manager.cc:1540] Loading token signing keys...
14:23:06.753 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.752909  6590 catalog_manager.cc:6070] T 00000000000000000000000000000000 P 82f068dceb854634bfcbcbbeae37715c: Generated new TSK 0
14:23:06.755 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.754949  6590 catalog_manager.cc:1550] Initializing in-progress tserver states...
14:23:06.791 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:06.787580  6321 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/wal
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:06.792 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/logs
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data/info.pb
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.65:0
14:23:06.793 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.44.65
14:23:06.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.65
14:23:06.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:06.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:06.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:06.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:06.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:06.796 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:23:07.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.217803  6558 catalog_manager.cc:1295] Loaded cluster ID: d9d07eeaac9644c7ad32aa86fe79230e
14:23:07.218 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.217926  6558 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: loading cluster ID for follower catalog manager: success
14:23:07.222 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.221901  6558 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: acquiring CA information for follower catalog manager: success
14:23:07.224 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.224689  6558 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 3b97b8274f5a40b3abafc17918036a27: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:23:07.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.244086  6600 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:23:07.244 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.244586  6600 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:23:07.245 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.244750  6600 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:23:07.258 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.258545  6600 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:23:07.259 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.258872  6600 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.6.44.65
14:23:07.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.275920  6600 tablet_server_runner.cc:78] Tablet server non-default flags:
14:23:07.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:07.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:07.276 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/wal
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.65:0
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:07.277 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data/info.pb
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.65
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.44.65
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/logs
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:07.278 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:53 UTC on e7f111948823
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15853
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
14:23:07.279 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.278389  6600 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:23:07.281 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.281159  6600 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:23:07.293 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.293174  6605 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:23:07.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.293211  6608 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:23:07.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.293295  6606 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:23:07.294 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.294485  6600 server_base.cc:1061] running on GCE node
14:23:07.295 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.295313  6600 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:23:07.297 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.296856  6600 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:23:07.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.298115  6600 hybrid_clock.cc:648] HybridClock initialized: now 1779114187298042 us; error 77 us; skew 500 ppm
14:23:07.298 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.298544  6600 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:23:07.301 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.301457  6600 webserver.cc:533] Webserver started at http://127.6.44.65:38633/ using document root <none> and password file <none>
14:23:07.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.302404  6600 fs_manager.cc:362] Metadata directory not provided
14:23:07.302 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.302563  6600 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:23:07.303 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.303115  6600 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:23:07.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.305961  6600 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data/instance:
14:23:07.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cd80edf13fb644bcaf27005a5bd9e03c"
14:23:07.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:07 on dist-test-slave-psp9"
14:23:07.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.307080  6600 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/wal/instance:
14:23:07.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cd80edf13fb644bcaf27005a5bd9e03c"
14:23:07.307 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:07 on dist-test-slave-psp9"
14:23:07.316 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.315753  6600 fs_manager.cc:696] Time spent creating directory manager: real 0.008s	user 0.006s	sys 0.001s
14:23:07.320 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.319882  6614 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:23:07.321 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.321513  6600 fs_manager.cc:730] Time spent opening block manager: real 0.004s	user 0.003s	sys 0.001s
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.321741  6600 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data,/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/wal
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "cd80edf13fb644bcaf27005a5bd9e03c"
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:07 on dist-test-slave-psp9"
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.322062  6600 fs_report.cc:389] FS layout report
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/wal
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/wal
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data/data
14:23:07.322 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:23:07.323 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:23:07.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.351851  6600 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:23:07.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.353016  6600 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:23:07.356 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.353396  6600 kserver.cc:163] Server-wide thread pool size limit: 3276
14:23:07.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.354593  6600 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:23:07.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.356539  6600 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:23:07.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.356685  6600 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:23:07.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.356802  6600 ts_tablet_manager.cc:616] Registered 0 tablets
14:23:07.357 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.356889  6600 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:23:07.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.394789  6600 rpc_server.cc:307] RPC server started. Bound to: 127.6.44.65:37869
14:23:07.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.394814  6726 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.44.65:37869 every 8 connection(s)
14:23:07.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.396343  6600 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/data/info.pb
14:23:07.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.405054  6321 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu as pid 6600
14:23:07.407 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.405309  6321 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-0/wal/instance
14:23:07.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.411015  6321 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:07.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:07.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/wal
14:23:07.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data
14:23:07.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:23:07.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:23:07.411 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/logs
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data/info.pb
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:23:07.412 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.66:0
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.44.66
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.66
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:07.413 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:07.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:07.414 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:23:07.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.436667  6728 heartbeater.cc:344] Connected to a master server at 127.6.44.126:40335
14:23:07.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.436596  6729 heartbeater.cc:344] Connected to a master server at 127.6.44.125:39087
14:23:07.437 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.437206  6729 heartbeater.cc:461] Registering TS with master...
14:23:07.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.437912  6728 heartbeater.cc:461] Registering TS with master...
14:23:07.439 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.438488  6729 heartbeater.cc:507] Master 127.6.44.125:39087 requested a full tablet report, sending...
14:23:07.440 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.438656  6728 heartbeater.cc:507] Master 127.6.44.126:40335 requested a full tablet report, sending...
14:23:07.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.441787  6363 ts_manager.cc:194] Registered new tserver with Master: cd80edf13fb644bcaf27005a5bd9e03c (127.6.44.65:37869)
14:23:07.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.441918  6434 ts_manager.cc:194] Registered new tserver with Master: cd80edf13fb644bcaf27005a5bd9e03c (127.6.44.65:37869)
14:23:07.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.443711  6586 catalog_manager.cc:1295] Loaded cluster ID: d9d07eeaac9644c7ad32aa86fe79230e
14:23:07.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.443816  6586 catalog_manager.cc:1588] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: loading cluster ID for follower catalog manager: success
14:23:07.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.444514  6363 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.6.44.65:35001
14:23:07.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.445376  6727 heartbeater.cc:344] Connected to a master server at 127.6.44.124:35199
14:23:07.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.445554  6727 heartbeater.cc:461] Registering TS with master...
14:23:07.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.446201  6727 heartbeater.cc:507] Master 127.6.44.124:35199 requested a full tablet report, sending...
14:23:07.447 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.447623  6586 catalog_manager.cc:1610] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: acquiring CA information for follower catalog manager: success
14:23:07.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.448988  6505 ts_manager.cc:194] Registered new tserver with Master: cd80edf13fb644bcaf27005a5bd9e03c (127.6.44.65:37869)
14:23:07.450 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.450402  6586 catalog_manager.cc:1638] T 00000000000000000000000000000000 P 335e03033f444e7e99254e41d4256e19: importing token verification keys for follower catalog manager: success; most recent TSK sequence number 0
14:23:07.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.784766  6737 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:23:07.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.785138  6737 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:23:07.785 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.785221  6737 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:23:07.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.793797  6737 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:23:07.794 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.793996  6737 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.6.44.66
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.804615  6737 tablet_server_runner.cc:78] Tablet server non-default flags:
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/wal
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.66:0
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data/info.pb
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.66
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:07.805 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.44.66
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/logs
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:53 UTC on e7f111948823
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15853
14:23:07.806 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
14:23:07.807 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.806529  6737 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:23:07.809 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.809247  6737 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:23:07.822 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.822117  6745 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:23:07.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.822086  6743 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:23:07.823 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:07.822160  6742 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:23:07.828 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.828312  6737 server_base.cc:1061] running on GCE node
14:23:07.829 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.829103  6737 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:23:07.830 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.830256  6737 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:23:07.831 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.831512  6737 hybrid_clock.cc:648] HybridClock initialized: now 1779114187831436 us; error 63 us; skew 500 ppm
14:23:07.832 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.831950  6737 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:23:07.834 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.834513  6737 webserver.cc:533] Webserver started at http://127.6.44.66:46755/ using document root <none> and password file <none>
14:23:07.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.835425  6737 fs_manager.cc:362] Metadata directory not provided
14:23:07.835 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.835570  6737 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:23:07.836 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.836016  6737 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:23:07.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.838722  6737 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data/instance:
14:23:07.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "196818f156ad4afdb79c93f41416538a"
14:23:07.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:07 on dist-test-slave-psp9"
14:23:07.839 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.839694  6737 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/wal/instance:
14:23:07.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "196818f156ad4afdb79c93f41416538a"
14:23:07.840 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:07 on dist-test-slave-psp9"
14:23:07.846 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.845831  6737 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.005s	sys 0.000s
14:23:07.850 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.849951  6751 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:23:07.851 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.851670  6737 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.003s	sys 0.000s
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.851877  6737 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data,/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/wal
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "196818f156ad4afdb79c93f41416538a"
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:07 on dist-test-slave-psp9"
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.852141  6737 fs_report.cc:389] FS layout report
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/wal
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/wal
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data/data
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:23:07.852 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:23:07.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:23:07.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:23:07.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:23:07.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:23:07.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:23:07.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:23:07.853 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:23:07.875 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.875367  6737 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:23:07.876 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.876513  6737 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:23:07.877 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.876884  6737 kserver.cc:163] Server-wide thread pool size limit: 3276
14:23:07.878 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.878134  6737 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:23:07.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.880210  6737 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:23:07.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.880352  6737 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:23:07.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.880472  6737 ts_tablet_manager.cc:616] Registered 0 tablets
14:23:07.880 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.880553  6737 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:23:07.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.918251  6737 rpc_server.cc:307] RPC server started. Bound to: 127.6.44.66:36393
14:23:07.918 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.918303  6863 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.44.66:36393 every 8 connection(s)
14:23:07.920 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.920027  6737 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/data/info.pb
14:23:07.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.923276  6321 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu as pid 6737
14:23:07.923 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.923497  6321 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-1/wal/instance
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.931813  6321 external_mini_cluster.cc:1366] Running /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/wal
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --block_manager=log
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=localhost
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:07.932 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/logs
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data/info.pb
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) tserver
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) run
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.67:0
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.44.67
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.67
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:07.933 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:07.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:07.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:07.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:07.934 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin with env {}
14:23:07.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.941964  6865 heartbeater.cc:344] Connected to a master server at 127.6.44.126:40335
14:23:07.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.942471  6865 heartbeater.cc:461] Registering TS with master...
14:23:07.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.943061  6866 heartbeater.cc:344] Connected to a master server at 127.6.44.125:39087
14:23:07.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.943220  6866 heartbeater.cc:461] Registering TS with master...
14:23:07.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.944096  6865 heartbeater.cc:507] Master 127.6.44.126:40335 requested a full tablet report, sending...
14:23:07.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.944379  6866 heartbeater.cc:507] Master 127.6.44.125:39087 requested a full tablet report, sending...
14:23:07.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.945569  6864 heartbeater.cc:344] Connected to a master server at 127.6.44.124:35199
14:23:07.945 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.945742  6864 heartbeater.cc:461] Registering TS with master...
14:23:07.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.946198  6363 ts_manager.cc:194] Registered new tserver with Master: 196818f156ad4afdb79c93f41416538a (127.6.44.66:36393)
14:23:07.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.946252  6864 heartbeater.cc:507] Master 127.6.44.124:35199 requested a full tablet report, sending...
14:23:07.946 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.946301  6434 ts_manager.cc:194] Registered new tserver with Master: 196818f156ad4afdb79c93f41416538a (127.6.44.66:36393)
14:23:07.947 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.947383  6363 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.6.44.66:50033
14:23:07.948 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:07.947479  6505 ts_manager.cc:194] Registered new tserver with Master: 196818f156ad4afdb79c93f41416538a (127.6.44.66:36393)
14:23:08.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.304896  6871 flags.cc:432] Enabled unsafe flag: --openssl_security_level_override=0
14:23:08.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.305303  6871 flags.cc:432] Enabled unsafe flag: --rpc_server_allow_ephemeral_ports=true
14:23:08.305 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.305418  6871 flags.cc:432] Enabled unsafe flag: --never_fsync=true
14:23:08.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.314755  6871 flags.cc:432] Enabled experimental flag: --ipki_server_key_size=768
14:23:08.315 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.315385  6871 flags.cc:432] Enabled experimental flag: --local_ip_for_outbound_sockets=127.6.44.67
14:23:08.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.326242  6871 tablet_server_runner.cc:78] Tablet server non-default flags:
14:23:08.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_poll_interval_ms=100
14:23:08.326 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --builtin_ntp_servers=127.6.44.84:40125
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ntp_initial_sync_wait_secs=10
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --time_source=builtin
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_data_dirs=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --fs_wal_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/wal
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --ipki_server_key_size=768
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --openssl_security_level_override=0
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_bind_addresses=127.6.44.67:0
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --rpc_server_allow_ephemeral_ports=true
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --metrics_log_interval_ms=1000
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_format=pb
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --server_dump_info_path=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data/info.pb
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_interface=127.6.44.67
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --webserver_port=0
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --tserver_master_addrs=127.6.44.126:40335,127.6.44.125:39087,127.6.44.124:35199
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --never_fsync=true
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --redact=none
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_experimental_flags=true
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --unlock_unsafe_flags=true
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --enable_minidumps=false
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --local_ip_for_outbound_sockets=127.6.44.67
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --log_dir=/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/logs
14:23:08.327 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logbuflevel=-1
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --logtostderr=true
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Tablet server version:
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) kudu 1.19.0-SNAPSHOT
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) revision 6bee9a2d051767e07c70d440ae169cb7177a10f8
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build type FASTDEBUG
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) built by None at 18 May 2026 14:03:53 UTC on e7f111948823
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) build id 15853
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) ASAN enabled
14:23:08.328 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.328135  6871 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:23:08.330 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.330211  6871 file_cache.cc:492] Constructed file cache file cache with capacity 419430
14:23:08.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.340652  6876 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:23:08.341 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.340631  6877 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:23:08.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:08.341145  6879 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:23:08.342 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.342185  6871 server_base.cc:1061] running on GCE node
14:23:08.343 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.342974  6871 hybrid_clock.cc:584] initializing the hybrid clock with 'builtin' time source
14:23:08.344 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.344094  6871 hybrid_clock.cc:630] waiting up to --ntp_initial_sync_wait_secs=10 seconds for the clock to synchronize
14:23:08.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.345347  6871 hybrid_clock.cc:648] HybridClock initialized: now 1779114188345303 us; error 39 us; skew 500 ppm
14:23:08.345 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.345800  6871 server_base.cc:861] Flag tcmalloc_max_total_thread_cache_bytes is not working since tcmalloc is not enabled.
14:23:08.348 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.348268  6871 webserver.cc:533] Webserver started at http://127.6.44.67:37125/ using document root <none> and password file <none>
14:23:08.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.349025  6871 fs_manager.cc:362] Metadata directory not provided
14:23:08.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.349175  6871 fs_manager.cc:368] Using write-ahead log directory (fs_wal_dir) as metadata directory
14:23:08.349 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.349620  6871 server_base.cc:909] This appears to be a new deployment of Kudu; creating new FS layout
14:23:08.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.352336  6871 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data/instance:
14:23:08.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "61e08c012d064182867a632ccbf5f8e4"
14:23:08.352 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:08 on dist-test-slave-psp9"
14:23:08.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.353256  6871 fs_manager.cc:1068] Generated new instance metadata in path /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/wal/instance:
14:23:08.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "61e08c012d064182867a632ccbf5f8e4"
14:23:08.353 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:08 on dist-test-slave-psp9"
14:23:08.359 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.359522  6871 fs_manager.cc:696] Time spent creating directory manager: real 0.006s	user 0.002s	sys 0.005s
14:23:08.363 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.363487  6885 log_block_manager.cc:3869] Time spent loading block containers with low live blocks: real 0.000s	user 0.000s	sys 0.000s
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.365032  6871 fs_manager.cc:730] Time spent opening block manager: real 0.003s	user 0.004s	sys 0.000s
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.365243  6871 fs_manager.cc:647] Opened local filesystem: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data,/tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/wal
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) uuid: "61e08c012d064182867a632ccbf5f8e4"
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) format_stamp: "Formatted at 2026-05-18 14:23:08 on dist-test-slave-psp9"
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.365528  6871 fs_report.cc:389] FS layout report
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) --------------------
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) wal directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/wal
14:23:08.365 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) metadata directory: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/wal
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 1 data directories: /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data/data
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live blocks: 0
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes: 0
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total live bytes (after alignment): 0
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total number of LBM containers: 0 (0 full)
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for missing blocks
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Did not check for orphaned blocks
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM containers with extra space: 0 (0 repaired)
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total full LBM container extra space in bytes: 0 (0 repaired)
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total incomplete LBM containers: 0 (0 repaired)
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total LBM partial records: 0 (0 repaired)
14:23:08.366 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) Total corrupted LBM metadata records in RocksDB: 0 (0 repaired)
14:23:08.394 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.394498  6871 rpc_server.cc:225] running with OpenSSL 1.1.1  11 Sep 2018
14:23:08.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.395807  6871 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:23:08.396 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.396209  6871 kserver.cc:163] Server-wide thread pool size limit: 3276
14:23:08.397 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.397588  6871 txn_system_client.cc:432] TxnSystemClient initialization is disabled...
14:23:08.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.400197  6871 ts_tablet_manager.cc:585] Loaded tablet metadata (0 total tablets, 0 live tablets)
14:23:08.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.400327  6871 ts_tablet_manager.cc:531] Time spent load tablet metadata: real 0.000s	user 0.000s	sys 0.000s
14:23:08.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.400444  6871 ts_tablet_manager.cc:616] Registered 0 tablets
14:23:08.400 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.400513  6871 ts_tablet_manager.cc:595] Time spent register tablets: real 0.000s	user 0.000s	sys 0.000s
14:23:08.441 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.441608  6871 rpc_server.cc:307] RPC server started. Bound to: 127.6.44.67:46397
14:23:08.442 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.441674  6997 acceptor_pool.cc:272] collecting diagnostics on the listening RPC socket 127.6.44.67:46397 every 8 connection(s)
14:23:08.443 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.443626  6871 server_base.cc:1193] Dumped server information to /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/data/info.pb
14:23:08.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.443964  6321 external_mini_cluster.cc:1428] Started /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu as pid 6871
14:23:08.444 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.444114  6321 external_mini_cluster.cc:1442] Reading /tmp/dist-test-taskhqtKoN/test-tmp/mini-kudu-cluster2278931833237824672/ts-2/wal/instance
14:23:08.449 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.449005  6728 heartbeater.cc:499] Master 127.6.44.126:40335 was elected leader, sending a full tablet report...
14:23:08.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.481804  6998 heartbeater.cc:344] Connected to a master server at 127.6.44.124:35199
14:23:08.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.481904  6999 heartbeater.cc:344] Connected to a master server at 127.6.44.126:40335
14:23:08.482 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.482290  6998 heartbeater.cc:461] Registering TS with master...
14:23:08.483 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.483641  6998 heartbeater.cc:507] Master 127.6.44.124:35199 requested a full tablet report, sending...
14:23:08.484 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.482295  6999 heartbeater.cc:461] Registering TS with master...
14:23:08.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.485599  6999 heartbeater.cc:507] Master 127.6.44.126:40335 requested a full tablet report, sending...
14:23:08.485 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.485723  6505 ts_manager.cc:194] Registered new tserver with Master: 61e08c012d064182867a632ccbf5f8e4 (127.6.44.67:46397)
14:23:08.487 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.487277  6363 ts_manager.cc:194] Registered new tserver with Master: 61e08c012d064182867a632ccbf5f8e4 (127.6.44.67:46397)
14:23:08.488 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.488399  6363 master_service.cc:502] Signed X509 certificate for tserver {username='slave'} at 127.6.44.67:46107
14:23:08.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.491477  7000 heartbeater.cc:344] Connected to a master server at 127.6.44.125:39087
14:23:08.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.491662  7000 heartbeater.cc:461] Registering TS with master...
14:23:08.492 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.492065  7000 heartbeater.cc:507] Master 127.6.44.125:39087 requested a full tablet report, sending...
14:23:08.493 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.493453  6434 ts_manager.cc:194] Registered new tserver with Master: 61e08c012d064182867a632ccbf5f8e4 (127.6.44.67:46397)
14:23:08.498 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.498533  6321 external_mini_cluster.cc:949] 3 TS(s) registered with all masters
14:23:08.500 [DEBUG - main] (MiniKuduCluster.java:195) Response: 
14:23:08.501 [DEBUG - main] (MiniKuduCluster.java:185) Request: get_masters {
}

14:23:08.511 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_masters {
  masters {
    id {
      type: MASTER
      index: 0
    }
    bound_rpc_address {
      host: "127.6.44.126"
      port: 40335
    }
    bound_http_address {
      host: "127.6.44.126"
      port: 43525
    }
  }
  masters {
    id {
      type: MASTER
      index: 1
    }
    bound_rpc_address {
      host: "127.6.44.125"
      port: 39087
    }
    bound_http_address {
      host: "127.6.44.125"
      port: 32933
    }
  }
  masters {
    id {
      type: MASTER
      index: 2
    }
    bound_rpc_address {
      host: "127.6.44.124"
      port: 35199
    }
    bound_http_address {
      host: "127.6.44.124"
      port: 37225
    }
  }
}

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

14:23:08.517 [DEBUG - main] (MiniKuduCluster.java:195) Response: get_tservers {
  tservers {
    id {
      type: TSERVER
      index: 0
    }
    bound_rpc_address {
      host: "127.6.44.65"
      port: 37869
    }
    bound_http_address {
      host: "127.6.44.65"
      port: 38633
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 1
    }
    bound_rpc_address {
      host: "127.6.44.66"
      port: 36393
    }
    bound_http_address {
      host: "127.6.44.66"
      port: 46755
    }
  }
  tservers {
    id {
      type: TSERVER
      index: 2
    }
    bound_rpc_address {
      host: "127.6.44.67"
      port: 46397
    }
    bound_http_address {
      host: "127.6.44.67"
      port: 37125
    }
  }
}

14:23:08.517 [INFO - main] (KuduTestHarness.java:157) Creating a new Kudu client...
14:23:08.713 [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:23:08.768 [DEBUG - main] (SecurityUtil.java:122) Could not login via JAAS. Using no credentials: Unable to obtain Principal Name for authentication 
14:23:08.915 [DEBUG - main] (Connection.java:748) [peer master-127.6.44.124:35199(127.6.44.124:35199)] connecting to peer
14:23:08.950 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:08.950089  6865 heartbeater.cc:499] Master 127.6.44.126:40335 was elected leader, sending a full tablet report...
14:23:08.971 [DEBUG - main] (Connection.java:748) [peer master-127.6.44.126:40335(127.6.44.126:40335)] connecting to peer
14:23:08.973 [DEBUG - main] (Connection.java:748) [peer master-127.6.44.125:39087(127.6.44.125:39087)] connecting to peer
14:23:09.007 [DEBUG - kudu-nio-0] (Connection.java:757) [peer master-127.6.44.124:35199(127.6.44.124:35199)] Successfully connected to peer
14:23:09.012 [DEBUG - kudu-nio-2] (Connection.java:757) [peer master-127.6.44.125:39087(127.6.44.125:39087)] Successfully connected to peer
14:23:09.013 [DEBUG - kudu-nio-1] (Connection.java:757) [peer master-127.6.44.126:40335(127.6.44.126:40335)] Successfully connected to peer
14:23:09.122 [DEBUG - kudu-nio-0] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.6.44.124
14:23:09.123 [DEBUG - kudu-nio-1] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.6.44.126
14:23:09.122 [DEBUG - kudu-nio-2] (Negotiator.java:529) SASL mechanism PLAIN chosen for peer 127.6.44.125
14:23:09.374 [DEBUG - kudu-nio-1] (Negotiator.java:919) Authenticated connection [id: 0x2ac766d6, L:/127.0.0.1:39716 - R:/127.6.44.126:40335] using SASL/PLAIN
14:23:09.374 [DEBUG - kudu-nio-0] (Negotiator.java:919) Authenticated connection [id: 0x9a37c536, L:/127.0.0.1:42580 - R:/127.6.44.124:35199] using SASL/PLAIN
14:23:09.377 [DEBUG - kudu-nio-2] (Negotiator.java:919) Authenticated connection [id: 0x882e7d59, L:/127.0.0.1:50500 - R:/127.6.44.125:39087] using SASL/PLAIN
14:23:09.411 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet Kudu Master for table 'Kudu Master' with partition [<start>, <end>)
14:23:09.412 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=CreateTable, tablet=null, attempt=1, TimeoutTracker(timeout=50000, elapsed=535), Traces: [0ms] refreshing cache from master, [25ms] Sub RPC ConnectToMaster: sending RPC to server master-127.6.44.124:35199, [91ms] Sub RPC ConnectToMaster: sending RPC to server master-127.6.44.126:40335, [93ms] Sub RPC ConnectToMaster: sending RPC to server master-127.6.44.125:39087, [508ms] Sub RPC ConnectToMaster: received response from server master-127.6.44.124:35199: OK, [508ms] Sub RPC ConnectToMaster: received response from server master-127.6.44.125:39087: OK, [509ms] Sub RPC ConnectToMaster: received response from server master-127.6.44.126:40335: OK, deferred=null) after lookup
14:23:09.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.467185  6363 catalog_manager.cc:2283] Servicing CreateTable request from {username='slave'} at 127.0.0.1:39716:
14:23:09.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) name: "org.apache.kudu.client.TestHybridTime-1779114184036"
14:23:09.467 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) schema {
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   columns {
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     name: "key"
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     type: STRING
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_key: true
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_nullable: false
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     cfile_block_size: 0
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     immutable: false
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     is_auto_incrementing: false
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) partition_schema {
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   range_schema {
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     columns {
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)       name: "key"
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)     }
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751)   }
14:23:09.468 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) }
14:23:09.469 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:09.468873  6363 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-1779114184036 in case of a server failure: 4 tablet servers would be needed, 3 are available. Consider bringing up more tablet servers.
14:23:09.491 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.491441  6999 heartbeater.cc:499] Master 127.6.44.126:40335 was elected leader, sending a full tablet report...
14:23:09.523 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.522120  6799 tablet_service.cc:1511] Processing CreateTablet for tablet a935f5ea74984789996294259983504b (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114184036 [id=c6e2605e9b564a749f6229d5e85fd2d2]), partition=RANGE (key) PARTITION UNBOUNDED
14:23:09.524 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.524394  6799 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a935f5ea74984789996294259983504b. 1 dirs total, 0 dirs full, 0 dirs failed
14:23:09.532 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:894) Opened table c6e2605e9b564a749f6229d5e85fd2d2
14:23:09.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.532917  6662 tablet_service.cc:1511] Processing CreateTablet for tablet a935f5ea74984789996294259983504b (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114184036 [id=c6e2605e9b564a749f6229d5e85fd2d2]), partition=RANGE (key) PARTITION UNBOUNDED
14:23:09.534 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.533870  6933 tablet_service.cc:1511] Processing CreateTablet for tablet a935f5ea74984789996294259983504b (DEFAULT_TABLE table=org.apache.kudu.client.TestHybridTime-1779114184036 [id=c6e2605e9b564a749f6229d5e85fd2d2]), partition=RANGE (key) PARTITION UNBOUNDED
14:23:09.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.535218  6662 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a935f5ea74984789996294259983504b. 1 dirs total, 0 dirs full, 0 dirs failed
14:23:09.535 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.535830  6933 data_dirs.cc:400] Could only allocate 1 dirs of requested 3 for tablet a935f5ea74984789996294259983504b. 1 dirs total, 0 dirs full, 0 dirs failed
14:23:09.561 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.561514  7032 tablet_bootstrap.cc:492] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c: Bootstrap starting.
14:23:09.564 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.564600  7033 tablet_bootstrap.cc:492] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4: Bootstrap starting.
14:23:09.568 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.568626  7032 tablet_bootstrap.cc:654] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c: Neither blocks nor log segments found. Creating new log.
14:23:09.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.570699  7033 tablet_bootstrap.cc:654] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4: Neither blocks nor log segments found. Creating new log.
14:23:09.571 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.571565  7032 log.cc:826] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c: Log is configured to *not* fsync() on all Append() calls
14:23:09.572 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.572131  7033 log.cc:826] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4: Log is configured to *not* fsync() on all Append() calls
14:23:09.574 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.573489  7034 tablet_bootstrap.cc:492] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a: Bootstrap starting.
14:23:09.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.577445  7034 tablet_bootstrap.cc:654] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a: Neither blocks nor log segments found. Creating new log.
14:23:09.577 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.577612  7032 tablet_bootstrap.cc:492] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c: No bootstrap required, opened a new log
14:23:09.578 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.577966  7032 ts_tablet_manager.cc:1403] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c: Time spent bootstrapping tablet: real 0.020s	user 0.006s	sys 0.003s
14:23:09.582 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.582469  7034 log.cc:826] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a: Log is configured to *not* fsync() on all Append() calls
14:23:09.583 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.583516  7033 tablet_bootstrap.cc:492] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4: No bootstrap required, opened a new log
14:23:09.584 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.583880  7033 ts_tablet_manager.cc:1403] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4: Time spent bootstrapping tablet: real 0.020s	user 0.008s	sys 0.000s
14:23:09.585 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.585793  7034 tablet_bootstrap.cc:492] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a: No bootstrap required, opened a new log
14:23:09.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.586102  7034 ts_tablet_manager.cc:1403] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a: Time spent bootstrapping tablet: real 0.013s	user 0.005s	sys 0.002s
14:23:09.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.586292  7032 raft_consensus.cc:359] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.586 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.586737  7032 raft_consensus.cc:385] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:23:09.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.586863  7032 raft_consensus.cc:740] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: cd80edf13fb644bcaf27005a5bd9e03c, State: Initialized, Role: FOLLOWER
14:23:09.587 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.587579  7032 consensus_queue.cc:260] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [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: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.591 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.591512  7033 raft_consensus.cc:359] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.592152  7033 raft_consensus.cc:385] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:23:09.592 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.592288  7033 raft_consensus.cc:740] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 61e08c012d064182867a632ccbf5f8e4, State: Initialized, Role: FOLLOWER
14:23:09.593 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.592978  7033 consensus_queue.cc:260] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [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: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.595 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.594892  7033 ts_tablet_manager.cc:1434] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4: Time spent starting tablet: real 0.011s	user 0.011s	sys 0.001s
14:23:09.596 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.596764  7032 ts_tablet_manager.cc:1434] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c: Time spent starting tablet: real 0.018s	user 0.009s	sys 0.000s
14:23:09.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.599664  7034 raft_consensus.cc:359] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [term 0 FOLLOWER]: Replica starting. Triggering 0 pending ops. Active config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.600162  7034 raft_consensus.cc:385] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [term 0 FOLLOWER]: Consensus starting up: Expiring failure detector timer to make a prompt election more likely
14:23:09.600 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.600286  7034 raft_consensus.cc:740] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [term 0 FOLLOWER]: Becoming Follower/Learner. State: Replica: 196818f156ad4afdb79c93f41416538a, State: Initialized, Role: FOLLOWER
14:23:09.601 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.601058  7034 consensus_queue.cc:260] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [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: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.603 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.603638  7034 ts_tablet_manager.cc:1434] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a: Time spent starting tablet: real 0.017s	user 0.005s	sys 0.008s
14:23:09.651 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:09.650894  6730 tablet.cc:2404] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:23:09.674 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:09.674106  6867 tablet.cc:2404] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:23:09.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.709240  7039 raft_consensus.cc:493] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Starting pre-election (no leader contacted us within the election timeout)
14:23:09.709 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.709535  7039 raft_consensus.cc:515] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Starting pre-election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.711 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.711540  7039 leader_election.cc:290] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [CANDIDATE]: Term 1 pre-election: Requested pre-vote from peers 196818f156ad4afdb79c93f41416538a (127.6.44.66:36393), cd80edf13fb644bcaf27005a5bd9e03c (127.6.44.65:37869)
14:23:09.721 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.721450  6819 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a935f5ea74984789996294259983504b" candidate_uuid: "61e08c012d064182867a632ccbf5f8e4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "196818f156ad4afdb79c93f41416538a" is_pre_election: true
14:23:09.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.721449  6682 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a935f5ea74984789996294259983504b" candidate_uuid: "61e08c012d064182867a632ccbf5f8e4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" is_pre_election: true
14:23:09.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.722115  6682 raft_consensus.cc:2468] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 61e08c012d064182867a632ccbf5f8e4 in term 0.
14:23:09.722 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.722113  6819 raft_consensus.cc:2468] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [term 0 FOLLOWER]: Leader pre-election vote request: Granting yes vote for candidate 61e08c012d064182867a632ccbf5f8e4 in term 0.
14:23:09.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.723011  6887 leader_election.cc:304] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [CANDIDATE]: Term 1 pre-election: Election decided. Result: candidate won. Election summary: received 3 responses out of 3 voters: 3 yes votes; 0 no votes. yes voters: 196818f156ad4afdb79c93f41416538a, 61e08c012d064182867a632ccbf5f8e4, cd80edf13fb644bcaf27005a5bd9e03c; no voters: 
14:23:09.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.723565  7039 raft_consensus.cc:2804] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Leader pre-election won for term 1
14:23:09.723 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.723737  7039 raft_consensus.cc:493] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Starting leader election (no leader contacted us within the election timeout)
14:23:09.724 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.723848  7039 raft_consensus.cc:3060] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 0 FOLLOWER]: Advancing to term 1
14:23:09.726 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.726011  7039 raft_consensus.cc:515] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 1 FOLLOWER]: Starting leader election with config: opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.726802  7039 leader_election.cc:290] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [CANDIDATE]: Term 1 election: Requested vote from peers 196818f156ad4afdb79c93f41416538a (127.6.44.66:36393), cd80edf13fb644bcaf27005a5bd9e03c (127.6.44.65:37869)
14:23:09.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.727371  6819 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a935f5ea74984789996294259983504b" candidate_uuid: "61e08c012d064182867a632ccbf5f8e4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "196818f156ad4afdb79c93f41416538a"
14:23:09.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.727511  6682 tablet_service.cc:1917] Received RequestConsensusVote() RPC: tablet_id: "a935f5ea74984789996294259983504b" candidate_uuid: "61e08c012d064182867a632ccbf5f8e4" candidate_term: 1 candidate_status { last_received { term: 0 index: 0 } } ignore_live_leader: false dest_uuid: "cd80edf13fb644bcaf27005a5bd9e03c"
14:23:09.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.727619  6819 raft_consensus.cc:3060] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [term 0 FOLLOWER]: Advancing to term 1
14:23:09.727 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.727715  6682 raft_consensus.cc:3060] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [term 0 FOLLOWER]: Advancing to term 1
14:23:09.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.731220  6682 raft_consensus.cc:2468] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 61e08c012d064182867a632ccbf5f8e4 in term 1.
14:23:09.731 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.731211  6819 raft_consensus.cc:2468] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [term 1 FOLLOWER]: Leader election vote request: Granting yes vote for candidate 61e08c012d064182867a632ccbf5f8e4 in term 1.
14:23:09.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.731822  6889 leader_election.cc:304] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [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: 61e08c012d064182867a632ccbf5f8e4, cd80edf13fb644bcaf27005a5bd9e03c; no voters: 
14:23:09.732 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.732353  7039 raft_consensus.cc:2804] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 1 FOLLOWER]: Leader election won for term 1
14:23:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.732976  7039 raft_consensus.cc:697] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [term 1 LEADER]: Becoming Leader. State: Replica: 61e08c012d064182867a632ccbf5f8e4, State: Running, Role: LEADER
14:23:09.733 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.733605  7039 consensus_queue.cc:237] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [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: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } }
14:23:09.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:09.739298  7001 tablet.cc:2404] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4: Can't schedule compaction. Clean time has not been advanced past its initial value.
14:23:09.740 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.739929  6362 catalog_manager.cc:5697] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 reported cstate change: term changed from 0 to 1, leader changed from <none> to 61e08c012d064182867a632ccbf5f8e4 (127.6.44.67). New cstate: current_term: 1 leader_uuid: "61e08c012d064182867a632ccbf5f8e4" committed_config { opid_index: -1 OBSOLETE_local: false peers { permanent_uuid: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 } health_report { overall_health: UNKNOWN } } peers { permanent_uuid: "61e08c012d064182867a632ccbf5f8e4" member_type: VOTER last_known_addr { host: "127.6.44.67" port: 46397 } health_report { overall_health: HEALTHY } } peers { permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 } health_report { overall_health: UNKNOWN } } }
14:23:09.811 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:2538) Learned about tablet a935f5ea74984789996294259983504b for table 'org.apache.kudu.client.TestHybridTime-1779114184036' with partition [<start>, <end>)
14:23:09.819 [DEBUG - kudu-nio-1] (TableLocationsCache.java:192) Discovered table locations:	[Tablet{lowerBoundPartitionKey=0x, upperBoundPartitionKey=0x, ttl=299992, tablet=a935f5ea74984789996294259983504b@[196818f156ad4afdb79c93f41416538a(127.6.44.66:36393),61e08c012d064182867a632ccbf5f8e4(127.6.44.67:46397)[L],cd80edf13fb644bcaf27005a5bd9e03c(127.6.44.65:37869)]}]
14:23:09.820 [DEBUG - kudu-nio-1] (AsyncKuduClient.java:1488) Retrying sending RPC KuduRpc(method=Write, tablet=null, attempt=1, sequence_id=-1, TimeoutTracker(timeout=30000, elapsed=19), Traces: [0ms] refreshing cache from master, [0ms] Sub RPC GetTableLocations: sending RPC to server master-127.6.44.126:40335, [9ms] Sub RPC GetTableLocations: received response from server master-127.6.44.126:40335: OK, deferred=null) after lookup
14:23:09.830 [DEBUG - kudu-nio-1] (Connection.java:748) [peer 61e08c012d064182867a632ccbf5f8e4(127.6.44.67:46397)] connecting to peer
14:23:09.833 [DEBUG - kudu-nio-3] (Connection.java:757) [peer 61e08c012d064182867a632ccbf5f8e4(127.6.44.67:46397)] Successfully connected to peer
14:23:09.900 [DEBUG - kudu-nio-3] (Negotiator.java:919) Authenticated connection [id: 0x25a0b79d, L:/127.0.0.1:57244 - R:/127.6.44.67:46397] using TOKEN/null
14:23:09.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.910493  6819 raft_consensus.cc:1275] T a935f5ea74984789996294259983504b P 196818f156ad4afdb79c93f41416538a [term 1 FOLLOWER]: Refusing update from remote peer 61e08c012d064182867a632ccbf5f8e4: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:23:09.911 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.910461  6682 raft_consensus.cc:1275] T a935f5ea74984789996294259983504b P cd80edf13fb644bcaf27005a5bd9e03c [term 1 FOLLOWER]: Refusing update from remote peer 61e08c012d064182867a632ccbf5f8e4: Log matching property violated. Preceding OpId in replica: term: 0 index: 0. Preceding OpId from leader: term: 1 index: 2. (index mismatch)
14:23:09.912 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.912319  7039 consensus_queue.cc:1048] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [LEADER]: Connected to new peer: Peer: permanent_uuid: "cd80edf13fb644bcaf27005a5bd9e03c" member_type: VOTER last_known_addr { host: "127.6.44.65" port: 37869 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:23:09.914 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.913339  7043 consensus_queue.cc:1048] T a935f5ea74984789996294259983504b P 61e08c012d064182867a632ccbf5f8e4 [LEADER]: Connected to new peer: Peer: permanent_uuid: "196818f156ad4afdb79c93f41416538a" member_type: VOTER last_known_addr { host: "127.6.44.66" port: 36393 }, Status: LMP_MISMATCH, Last received: 0.0, Next index: 1, Last known committed idx: 0, Time since last communication: 0.000s
14:23:09.944 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.943641  7048 mvcc.cc:204] Tried to move back new op lower bound from 7287251721860804608 to 7287251721153904640. Current Snapshot: MvccSnapshot[applied={T|T < 7287251721860804608}]
14:23:09.951 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.947014  7050 mvcc.cc:204] Tried to move back new op lower bound from 7287251721860804608 to 7287251721153904640. Current Snapshot: MvccSnapshot[applied={T|T < 7287251721860804608}]
14:23:09.966 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:09.965883  7049 mvcc.cc:204] Tried to move back new op lower bound from 7287251721860804608 to 7287251721153904640. Current Snapshot: MvccSnapshot[applied={T|T < 7287251721860804608}]
14:23:10.006 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:23:10.027 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:23:10.039 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:23:10.045 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1487707690(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:23:10.059 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:23:10.060 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1902259510(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:23:10.070 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:23:10.071 [DEBUG - Time-limited test] (AsyncKuduSession.java:592) flushing buffer: Buffer{operations=1, operationSize=26, flusherTask=null, flushNotification=Deferred@1750617225(state=PENDING, result=null, callback=<none>, errback=<none>)}
14:23:10.081 [DEBUG - Time-limited test] (TestHybridTime.java:121) Clock value after write[%d]: %s Logical value: %d
14:23:10.132 [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-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.152 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 1 rows, more = false, responseScanTimestamp = 7287251742340804609) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.160 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 2 rows, more = false, responseScanTimestamp = 7287251742340804615) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.167 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 3 rows, more = false, responseScanTimestamp = 7287251742340804618) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.174 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 4 rows, more = false, responseScanTimestamp = 7287251742340804622) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.181 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 5 rows, more = false, responseScanTimestamp = 7287251742340804626) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.189 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 6 rows, more = false, responseScanTimestamp = 7287251742340804631) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.195 [DEBUG - kudu-nio-3] (AsyncKuduScanner.java:1370) AsyncKuduScanner$Response(scannerId = "", data = RowwiseRowResultIterator for 7 rows, more = false, responseScanTimestamp = 7287251742340804632) for scanner KuduScanner(table=org.apache.kudu.client.TestHybridTime-1779114184036, tablet=a935f5ea74984789996294259983504b, scannerId=null, scanRequestTimeout=30000, startPrimaryKey=<start>, endPrimaryKey=<end>, lastPrimaryKey=<last>)
14:23:10.204 [DEBUG - main] (Connection.java:575) [peer 61e08c012d064182867a632ccbf5f8e4(127.6.44.67:46397)] disconnecting while in state READY
14:23:10.206 [DEBUG - main] (Connection.java:575) [peer master-127.6.44.125:39087(127.6.44.125:39087)] disconnecting while in state READY
14:23:10.206 [DEBUG - main] (Connection.java:575) [peer master-127.6.44.126:40335(127.6.44.126:40335)] disconnecting while in state READY
14:23:10.206 [DEBUG - main] (Connection.java:575) [peer master-127.6.44.124:35199(127.6.44.124:35199)] disconnecting while in state READY
14:23:10.213 [DEBUG - kudu-nio-0] (Connection.java:239) [peer master-127.6.44.124:35199(127.6.44.124:35199)] handling channelInactive
14:23:10.213 [DEBUG - kudu-nio-3] (Connection.java:239) [peer 61e08c012d064182867a632ccbf5f8e4(127.6.44.67:46397)] handling channelInactive
14:23:10.214 [DEBUG - kudu-nio-0] (Connection.java:709) [peer master-127.6.44.124:35199(127.6.44.124:35199)] cleaning up while in state READY due to: connection closed
14:23:10.215 [DEBUG - kudu-nio-1] (Connection.java:239) [peer master-127.6.44.126:40335(127.6.44.126:40335)] handling channelInactive
14:23:10.215 [DEBUG - kudu-nio-1] (Connection.java:709) [peer master-127.6.44.126:40335(127.6.44.126:40335)] cleaning up while in state READY due to: connection closed
14:23:10.216 [DEBUG - kudu-nio-2] (AsyncKuduClient.java:2704) Releasing all remaining resources
14:23:10.222 [DEBUG - kudu-nio-3] (Connection.java:709) [peer 61e08c012d064182867a632ccbf5f8e4(127.6.44.67:46397)] cleaning up while in state READY due to: connection closed
14:23:10.259 [DEBUG - kudu-nio-2] (Connection.java:239) [peer master-127.6.44.125:39087(127.6.44.125:39087)] handling channelInactive
14:23:10.259 [DEBUG - kudu-nio-2] (Connection.java:709) [peer master-127.6.44.125:39087(127.6.44.125:39087)] cleaning up while in state READY due to: connection closed
14:23:10.260 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.259919  6321 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu pid 6600
14:23:10.291 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.290975  6722 generic_service.cc:196] Checking for leaks (request via RPC)
14:23:10.395 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.395423  6321 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu with pid 6600
14:23:10.416 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.416044  6321 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu pid 6737
14:23:10.424 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.424464  6859 generic_service.cc:196] Checking for leaks (request via RPC)
14:23:10.533 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.533440  6321 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu with pid 6737
14:23:10.551 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.551479  6321 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu pid 6871
14:23:10.560 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.560127  6993 generic_service.cc:196] Checking for leaks (request via RPC)
14:23:10.688 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.687731  6321 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu with pid 6871
14:23:10.707 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.706920  6321 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu pid 6332
14:23:10.708 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.708292  6393 generic_service.cc:196] Checking for leaks (request via RPC)
14:23:10.825 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.824998  6321 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu with pid 6332
14:23:10.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:10.847704  6489 connection.cc:582] server connection from 127.0.0.1:42572 recv error: Network error: recv error from unknown peer: Transport endpoint is not connected (error 107)
14:23:10.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) W20260518 14:23:10.847821  6420 connection.cc:582] server connection from 127.0.0.1:50486 recv error: Network error: recv error from unknown peer: Transport endpoint is not connected (error 107)
14:23:10.848 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.848224  6321 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu pid 6400
14:23:10.849 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.849368  6464 generic_service.cc:196] Checking for leaks (request via RPC)
14:23:10.959 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.958726  6321 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu with pid 6400
14:23:10.980 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.979928  6321 external_mini_cluster.cc:1699] Attempting to check leaks for /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu pid 6471
14:23:10.981 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:10.981213  6535 generic_service.cc:196] Checking for leaks (request via RPC)
14:23:11.089 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) I20260518 14:23:11.089311  6321 external_mini_cluster.cc:1658] Killing /tmp/dist-test-taskhqtKoN/build/asan/bin/kudu with pid 6471
14:23:11.139 [INFO - cluster stderr printer] (MiniKuduCluster.java:751) 2026-05-18T14:23:11Z chronyd exiting

Time: 7.129

OK (1 test)

